-- Logs begin at Thu 2019-02-14 18:11:59 CST, end at Sat 2025-12-27 16:54:48 CST. -- Dec 27 16:53:01 volumio volumio[701]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%9C%B4%E6%A0%91/%E6%88%91%E5%8E%BB2000%E5%B9%B4/f768d6bf-b50e-4ab6-af3b-6eb51d79eed1.jpg' Dec 27 16:53:06 volumio volumio[701]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%9C%B4%E6%A0%91/%E6%88%91%E5%8E%BB2000%E5%B9%B4/f768d6bf-b50e-4ab6-af3b-6eb51d79eed1.jpg' Dec 27 16:53:07 volumio kernel: hwmon hwmon1: Voltage normalised Dec 27 16:53:11 volumio volumio[701]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%86%AF%E6%9B%A6%E5%A6%A4/A%20Little%20Love/d1feff36-1ccb-4814-a759-9c6eb8bb39d9.jpg' Dec 27 16:53:18 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 27 16:53:18 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 27 16:53:18 volumio volumio[701]: info: Discovery: Getting this device information Dec 27 16:53:18 volumio volumio[701]: info: CoreCommandRouter::volumioGetState Dec 27 16:53:18 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 27 16:53:22 volumio volumio[701]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Dec 27 16:53:22 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Dec 27 16:53:22 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 27 16:53:22 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Dec 27 16:53:22 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 16:53:22 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName Dec 27 16:53:22 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 16:53:22 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 27 16:53:22 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Dec 27 16:53:22 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 16:53:22 volumio volumio[701]: info: Setting default mixerSoftMaster for Softvolume device Dec 27 16:53:22 volumio volumio[701]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 27 16:53:22 volumio volumio[701]: info: Updating Volume Controller Parameters: Device: 2 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 2 Dec 27 16:53:22 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 27 16:53:22 volumio volumio[701]: info: Disabling external Volume Control Dec 27 16:53:22 volumio volumio[701]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 27 16:53:22 volumio volumio[701]: info: Enable softmixer device for audio device undefined Dec 27 16:53:22 volumio volumio[701]: info: Software Volume ALSA configuration written Dec 27 16:53:22 volumio volumio[701]: info: Preparing to generate the ALSA configuration file Dec 27 16:53:22 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 27 16:53:22 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 16:53:22 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 27 16:53:22 volumio volumio[701]: info: Getting Alsa Cards List without I2S DAC Dec 27 16:53:22 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Dec 27 16:53:22 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 16:53:22 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 16:53:22 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 16:53:22 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 16:53:22 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 16:53:22 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 16:53:22 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 16:53:22 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 16:53:22 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 27 16:53:22 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 27 16:53:22 volumio volumio[701]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Dec 27 16:53:22 volumio volumio[701]: info: Reading ALSA contributions from plugins. Dec 27 16:53:22 volumio volumio[701]: info: CoreStateMachine::pushState Dec 27 16:53:22 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 16:53:22 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:53:22 volumio volumio[701]: info: CoreCommandRouter::volumioGetQueue Dec 27 16:53:22 volumio volumio[701]: info: CoreStateMachine::getQueue Dec 27 16:53:22 volumio volumio[701]: info: CorePlayQueue::getQueue Dec 27 16:53:22 volumio volumio[701]: info: Asound.conf file unchanged, so no further update is needed Dec 27 16:53:22 volumio volumio[701]: info: Output device has changed, restarting MPD Dec 27 16:53:22 volumio volumio[701]: info: Output device has changed, restarting Shairport Sync Dec 27 16:53:22 volumio sudo[7858]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 27 16:53:22 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 16:53:22 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 16:53:22 volumio sudo[7858]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 16:53:22 volumio sudo[7858]: pam_unix(sudo:session): session closed for user root Dec 27 16:53:22 volumio sudo[7860]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 27 16:53:22 volumio sudo[7860]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 16:53:22 volumio systemd[1]: Stopping Music Player Daemon... Dec 27 16:53:23 volumio volumio[701]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 27 16:53:23 volumio volumio[701]: info: MPD Permissions set Dec 27 16:53:23 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 16:53:23 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 16:53:23 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 16:53:23 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 16:53:23 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 16:53:23 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 16:53:23 volumio volumio[701]: info: Starting Shairport Sync Dec 27 16:53:23 volumio systemd[1]: mpd.service: Succeeded. Dec 27 16:53:23 volumio systemd[1]: Stopped Music Player Daemon. Dec 27 16:53:23 volumio sudo[7873]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 27 16:53:23 volumio sudo[7873]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 16:53:23 volumio systemd[1]: Starting Music Player Daemon... Dec 27 16:53:23 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 27 16:53:23 volumio systemd[1]: shairport-sync.service: Succeeded. Dec 27 16:53:23 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 27 16:53:23 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 27 16:53:23 volumio sudo[7873]: pam_unix(sudo:session): session closed for user root Dec 27 16:53:23 volumio volumio[701]: info: Shairport-Sync Started Dec 27 16:53:23 volumio sudo[7874]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 27 16:53:23 volumio sudo[7874]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 27 16:53:23 volumio sudo[7874]: pam_unix(sudo:session): session closed for user root Dec 27 16:53:24 volumio kernel: hwmon hwmon1: Undervoltage detected! Dec 27 16:53:25 volumio volumio[701]: info: CoreCommandRouter::volumioPause Dec 27 16:53:25 volumio volumio[701]: info: CoreStateMachine::pause Dec 27 16:53:25 volumio volumio[701]: info: CoreStateMachine::stPlaybackTimer Dec 27 16:53:25 volumio volumio[701]: info: CoreStateMachine::servicePause Dec 27 16:53:25 volumio volumio[701]: info: CorePlayQueue::getTrack 0 Dec 27 16:53:25 volumio volumio[701]: info: Error: no service or no trackblock to pause Dec 27 16:53:25 volumio mpd[7882]: Dec 27 16:53 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 27 16:53:25 volumio systemd[1]: Started Music Player Daemon. Dec 27 16:53:25 volumio sudo[7860]: pam_unix(sudo:session): session closed for user root Dec 27 16:53:25 volumio volumio[701]: error: updateQueue error: null Dec 27 16:53:26 volumio volumio[701]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 27 16:53:26 volumio volumio[701]: info: CoreStateMachine::getcurrentVolume Dec 27 16:53:26 volumio volumio[701]: info: CoreCommandRouter::volumioRetrievevolume Dec 27 16:53:26 volumio volumio[701]: info: CoreStateMachine::pushState Dec 27 16:53:26 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:53:26 volumio volumio[701]: info: CoreStateMachine::updateTrackBlock Dec 27 16:53:26 volumio volumio[701]: info: CorePlayQueue::getTrackBlock Dec 27 16:53:26 volumio volumio[701]: info: CoreCommandRouter::volumioRetrievevolume Dec 27 16:53:26 volumio volumio[701]: info: CoreCommandRouter::volumioGetQueue Dec 27 16:53:26 volumio volumio[701]: info: CoreStateMachine::getQueue Dec 27 16:53:26 volumio volumio[701]: info: CorePlayQueue::getQueue Dec 27 16:53:26 volumio volumio[701]: info: CoreStateMachine::pushState Dec 27 16:53:26 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:53:26 volumio volumio[701]: info: CoreCommandRouter::volumioGetQueue Dec 27 16:53:26 volumio volumio[701]: info: CoreStateMachine::getQueue Dec 27 16:53:26 volumio volumio[701]: info: CorePlayQueue::getQueue Dec 27 16:53:32 volumio kernel: hwmon hwmon1: Voltage normalised Dec 27 16:53:40 volumio volumio[701]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Dec 27 16:53:40 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Dec 27 16:53:40 volumio volumio[701]: info: CoreCommandRouter::volumioGetState Dec 27 16:53:40 volumio volumio[701]: info: Restoring Previous Volume level: 100 false true Dec 27 16:53:40 volumio volumio[701]: info: VolumeController::SetAlsaVolume100 Dec 27 16:53:40 volumio volumio[701]: info: Volume configurations have been set Dec 27 16:53:40 volumio volumio[701]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 27 16:53:40 volumio volumio[701]: info: Updating Volume Controller Parameters: Device: 2 Name: softvolume Mixer: SoftMaster Max Vol: 80 Vol Curve; logarithmic Vol Steps: 2 Dec 27 16:53:40 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 27 16:53:40 volumio volumio[701]: info: Disabling external Volume Control Dec 27 16:53:40 volumio volumio[701]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 27 16:53:40 volumio volumio[701]: info: CoreStateMachine::pushState Dec 27 16:53:40 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 16:53:40 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:53:40 volumio volumio[701]: info: CoreCommandRouter::volumioGetQueue Dec 27 16:53:40 volumio volumio[701]: info: CoreStateMachine::getQueue Dec 27 16:53:40 volumio volumio[701]: info: CorePlayQueue::getQueue Dec 27 16:53:40 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 27 16:53:40 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 16:53:40 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 27 16:53:40 volumio volumio[701]: info: Getting Alsa Cards List without I2S DAC Dec 27 16:53:41 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Dec 27 16:53:41 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 16:53:41 volumio kernel: hwmon hwmon1: Undervoltage detected! Dec 27 16:53:41 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 16:53:41 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 16:53:41 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 16:53:41 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 16:53:41 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 16:53:41 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 16:53:41 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 16:53:41 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 27 16:53:41 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 27 16:53:41 volumio volumio[701]: info: CoreStateMachine::pushState Dec 27 16:53:41 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 16:53:41 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:53:41 volumio volumio[701]: info: CoreCommandRouter::volumioGetQueue Dec 27 16:53:41 volumio volumio[701]: info: CoreStateMachine::getQueue Dec 27 16:53:41 volumio volumio[701]: info: CorePlayQueue::getQueue Dec 27 16:53:43 volumio volumio[701]: info: CoreCommandRouter::volumioPause Dec 27 16:53:43 volumio volumio[701]: info: CoreStateMachine::pause Dec 27 16:53:45 volumio volumio[701]: info: VolumeController::SetAlsaVolume100 Dec 27 16:53:45 volumio volumio[701]: info: CoreStateMachine::pushState Dec 27 16:53:45 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 16:53:45 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:53:45 volumio volumio[701]: info: CoreCommandRouter::volumioGetQueue Dec 27 16:53:45 volumio volumio[701]: info: CoreStateMachine::getQueue Dec 27 16:53:45 volumio volumio[701]: info: CorePlayQueue::getQueue Dec 27 16:53:47 volumio kernel: hwmon hwmon1: Voltage normalised Dec 27 16:53:48 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 27 16:53:48 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 27 16:53:48 volumio volumio[701]: info: Discovery: Getting this device information Dec 27 16:53:48 volumio volumio[701]: info: CoreCommandRouter::volumioGetState Dec 27 16:53:48 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 27 16:53:58 volumio volumio[701]: info: CoreCommandRouter::volumioPause Dec 27 16:53:58 volumio volumio[701]: info: CoreStateMachine::pause Dec 27 16:54:01 volumio volumio[701]: info: Clearing queue after UPNP request Dec 27 16:54:01 volumio volumio[701]: info: CoreStateMachine::ClearQueue Dec 27 16:54:01 volumio volumio[701]: info: CoreStateMachine::stop Dec 27 16:54:01 volumio volumio[701]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 16:54:01 volumio volumio[701]: info: CoreStateMachine::updateTrackBlock Dec 27 16:54:01 volumio volumio[701]: info: CorePlayQueue::getTrackBlock Dec 27 16:54:01 volumio volumio[701]: info: CoreStateMachine::stPlaybackTimer Dec 27 16:54:01 volumio volumio[701]: info: CoreStateMachine::pushState Dec 27 16:54:01 volumio volumio[701]: info: CorePlayQueue::getTrack 0 Dec 27 16:54:01 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 16:54:01 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:01 volumio volumio[701]: info: CoreStateMachine::serviceStop Dec 27 16:54:01 volumio volumio[701]: info: CorePlayQueue::getTrack 0 Dec 27 16:54:01 volumio volumio[701]: info: ControllerMpd::stop Dec 27 16:54:01 volumio volumio[701]: verbose: ControllerMpd::sendMpdCommand stop Dec 27 16:54:01 volumio volumio[701]: info: CorePlayQueue::clearPlayQueue Dec 27 16:54:01 volumio volumio[701]: info: CorePlayQueue::saveQueue Dec 27 16:54:01 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:01 volumio volumio[701]: info: CoreCommandRouter::volumioPushQueue Dec 27 16:54:01 volumio volumio[701]: info: Dec 27 16:54:01 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:01 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:01 volumio volumio[701]: info: sendMpdCommand stop took 9 milliseconds Dec 27 16:54:01 volumio volumio[701]: error: updateQueue error: null Dec 27 16:54:01 volumio volumio[701]: info: ------------------------------ 3ms Dec 27 16:54:01 volumio volumio[701]: info: Starting UPNP Playback Dec 27 16:54:01 volumio volumio[701]: info: Preparing playback through UPNP Dec 27 16:54:01 volumio volumio[701]: info: CoreCommandRouter::volumioGetState Dec 27 16:54:01 volumio volumio[701]: info: CorePlayQueue::getTrack 0 Dec 27 16:54:01 volumio volumio[701]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 27 16:54:02 volumio volumio[701]: info: Dec 27 16:54:02 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:02 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:02 volumio volumio[701]: info: ------------------------------ 2ms Dec 27 16:54:02 volumio volumio[701]: info: Dec 27 16:54:02 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:02 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:02 volumio volumio[701]: info: ------------------------------ 1ms Dec 27 16:54:02 volumio volumio[701]: info: Dec 27 16:54:02 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:02 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:02 volumio volumio[701]: info: ------------------------------ 2ms Dec 27 16:54:02 volumio volumio[701]: info: Dec 27 16:54:02 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:02 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:02 volumio volumio[701]: info: ------------------------------ 1ms Dec 27 16:54:02 volumio volumio[701]: info: Dec 27 16:54:02 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:02 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:02 volumio volumio[701]: info: ------------------------------ 2ms Dec 27 16:54:02 volumio volumio[701]: info: Dec 27 16:54:02 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:02 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:02 volumio volumio[701]: info: ------------------------------ 1ms Dec 27 16:54:02 volumio volumio[701]: info: Dec 27 16:54:02 volumio volumio[701]: ---------------------------- MPD announces state update: player Dec 27 16:54:02 volumio volumio[701]: info: ControllerMpd::getState Dec 27 16:54:02 volumio volumio[701]: verbose: ControllerMpd::sendMpdCommand status Dec 27 16:54:02 volumio volumio[701]: info: sendMpdCommand status took 1 milliseconds Dec 27 16:54:02 volumio volumio[701]: verbose: ControllerMpd::parseState Dec 27 16:54:02 volumio volumio[701]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 16:54:02 volumio volumio[701]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 27 16:54:02 volumio volumio[701]: verbose: ControllerMpd::parseTrackInfo Dec 27 16:54:02 volumio volumio[701]: info: ControllerMpd::pushState Dec 27 16:54:02 volumio volumio[701]: info: CoreCommandRouter::servicePushState Dec 27 16:54:02 volumio volumio[701]: info: CoreStateMachine::pushState Dec 27 16:54:02 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 16:54:02 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:02 volumio volumio[701]: info: CorePlayQueue::getTrack 0 Dec 27 16:54:02 volumio volumio[701]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Yesterday Once More","artist":"Carpenters","album":"Yesterday Once More","uri":"http://aqqmusic.tc.qq.com/M800003vyCD03Qjocc.mp3?guid=000000005307aef90000019af43fe5cf&vkey=3DAA7179EAC13141444B7C43DB0425773B8E4E0CBDE3D45DD6CC616C46DFD966CFF3D88DFC2A1531B5DDE5BDC59FEFE6A44DAC1BE11B4608__v21e2a18f6&uin=905090038&redirect=1&fromtag=111093","trackType":""} Dec 27 16:54:02 volumio volumio[701]: verbose: CURRENT POSITION 0 Dec 27 16:54:02 volumio volumio[701]: info: CoreStateMachine::syncState stateService stop Dec 27 16:54:02 volumio volumio[701]: info: CoreStateMachine::syncState currentStatus stop Dec 27 16:54:02 volumio volumio[701]: info: CoreStateMachine::pushState Dec 27 16:54:02 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:02 volumio volumio[701]: info: No code Dec 27 16:54:02 volumio volumio[701]: info: CoreStateMachine::pushState Dec 27 16:54:02 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:02 volumio volumio[701]: info: ------------------------------ 18ms Dec 27 16:54:02 volumio volumio[701]: info: CoreCommandRouter::volumioGetQueue Dec 27 16:54:02 volumio volumio[701]: info: CoreStateMachine::getQueue Dec 27 16:54:02 volumio volumio[701]: info: CorePlayQueue::getQueue Dec 27 16:54:02 volumio volumio[701]: info: CoreCommandRouter::volumioGetQueue Dec 27 16:54:02 volumio volumio[701]: info: CoreStateMachine::getQueue Dec 27 16:54:02 volumio volumio[701]: info: CorePlayQueue::getQueue Dec 27 16:54:02 volumio volumio[701]: info: CoreCommandRouter::volumioGetQueue Dec 27 16:54:02 volumio volumio[701]: info: CoreStateMachine::getQueue Dec 27 16:54:02 volumio volumio[701]: info: CorePlayQueue::getQueue Dec 27 16:54:02 volumio volumio[701]: info: Clearing queue after UPNP request Dec 27 16:54:02 volumio volumio[701]: info: CoreStateMachine::ClearQueue Dec 27 16:54:02 volumio volumio[701]: info: CoreStateMachine::stop Dec 27 16:54:02 volumio volumio[701]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 16:54:02 volumio volumio[701]: info: CorePlayQueue::clearPlayQueue Dec 27 16:54:02 volumio volumio[701]: info: CorePlayQueue::saveQueue Dec 27 16:54:02 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:02 volumio volumio[701]: info: CoreCommandRouter::volumioPushQueue Dec 27 16:54:02 volumio volumio[701]: info: Dec 27 16:54:02 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:02 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:02 volumio volumio[701]: error: updateQueue error: null Dec 27 16:54:02 volumio volumio[701]: info: ------------------------------ 2ms Dec 27 16:54:02 volumio volumio[701]: info: Starting UPNP Playback Dec 27 16:54:02 volumio volumio[701]: info: Preparing playback through UPNP Dec 27 16:54:02 volumio volumio[701]: info: CoreCommandRouter::volumioGetState Dec 27 16:54:02 volumio volumio[701]: info: CorePlayQueue::getTrack 0 Dec 27 16:54:02 volumio volumio[701]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 27 16:54:03 volumio volumio[701]: info: Dec 27 16:54:03 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:03 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:03 volumio volumio[701]: info: ------------------------------ 2ms Dec 27 16:54:03 volumio volumio[701]: info: Dec 27 16:54:03 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:03 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:03 volumio volumio[701]: info: ------------------------------ 1ms Dec 27 16:54:03 volumio volumio[701]: info: Dec 27 16:54:03 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:03 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:03 volumio volumio[701]: info: ------------------------------ 2ms Dec 27 16:54:03 volumio volumio[701]: info: Dec 27 16:54:03 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:03 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:03 volumio volumio[701]: info: ------------------------------ 1ms Dec 27 16:54:03 volumio volumio[701]: info: Dec 27 16:54:03 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:03 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:03 volumio volumio[701]: info: ------------------------------ 2ms Dec 27 16:54:03 volumio volumio[701]: info: Dec 27 16:54:03 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:03 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:03 volumio volumio[701]: info: ------------------------------ 1ms Dec 27 16:54:03 volumio volumio[701]: info: Dec 27 16:54:03 volumio volumio[701]: ---------------------------- MPD announces state update: player Dec 27 16:54:03 volumio volumio[701]: info: ControllerMpd::getState Dec 27 16:54:03 volumio volumio[701]: verbose: ControllerMpd::sendMpdCommand status Dec 27 16:54:03 volumio volumio[701]: info: sendMpdCommand status took 1 milliseconds Dec 27 16:54:03 volumio volumio[701]: verbose: ControllerMpd::parseState Dec 27 16:54:03 volumio volumio[701]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 16:54:03 volumio volumio[701]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 27 16:54:03 volumio volumio[701]: verbose: ControllerMpd::parseTrackInfo Dec 27 16:54:03 volumio volumio[701]: info: ControllerMpd::pushState Dec 27 16:54:03 volumio volumio[701]: info: CoreCommandRouter::servicePushState Dec 27 16:54:03 volumio volumio[701]: info: CoreStateMachine::pushState Dec 27 16:54:03 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:03 volumio volumio[701]: info: CorePlayQueue::getTrack 0 Dec 27 16:54:03 volumio volumio[701]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"难忘的一天","artist":"丽江小倩","album":"倩语倩寻","uri":"http://aqqmusic.tc.qq.com/M800001YP70H4KLLIY.mp3?guid=000000005307aef90000019af43fe5cf&vkey=69CB96CB1C7945D57577A1C165A9098174D0D4046E70C11B1B53390E34343C0936DE17B4E6F95AB0C1676528E0AD32B51FA63F6DB9280B04__v21e2a18f6&uin=905090038&src=M800000L68o44IZjLj.mp3&redirect=1&fromtag=111093","trackType":""} Dec 27 16:54:03 volumio volumio[701]: verbose: CURRENT POSITION 0 Dec 27 16:54:03 volumio volumio[701]: info: CoreStateMachine::syncState stateService stop Dec 27 16:54:03 volumio volumio[701]: info: CoreStateMachine::syncState currentStatus stop Dec 27 16:54:03 volumio volumio[701]: info: CoreStateMachine::pushState Dec 27 16:54:03 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:03 volumio volumio[701]: info: No code Dec 27 16:54:03 volumio volumio[701]: info: CoreStateMachine::pushState Dec 27 16:54:03 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:03 volumio volumio[701]: info: ------------------------------ 13ms Dec 27 16:54:03 volumio volumio[701]: info: CoreCommandRouter::volumioGetQueue Dec 27 16:54:03 volumio volumio[701]: info: CoreStateMachine::getQueue Dec 27 16:54:03 volumio volumio[701]: info: CorePlayQueue::getQueue Dec 27 16:54:03 volumio volumio[701]: info: CoreCommandRouter::volumioGetQueue Dec 27 16:54:03 volumio volumio[701]: info: CoreStateMachine::getQueue Dec 27 16:54:03 volumio volumio[701]: info: CorePlayQueue::getQueue Dec 27 16:54:03 volumio volumio[701]: info: CoreCommandRouter::volumioGetQueue Dec 27 16:54:03 volumio volumio[701]: info: CoreStateMachine::getQueue Dec 27 16:54:03 volumio volumio[701]: info: CorePlayQueue::getQueue Dec 27 16:54:03 volumio volumio[701]: info: Clearing queue after UPNP request Dec 27 16:54:03 volumio volumio[701]: info: CoreStateMachine::ClearQueue Dec 27 16:54:03 volumio volumio[701]: info: CoreStateMachine::stop Dec 27 16:54:03 volumio volumio[701]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 16:54:03 volumio volumio[701]: info: CorePlayQueue::clearPlayQueue Dec 27 16:54:03 volumio volumio[701]: info: CorePlayQueue::saveQueue Dec 27 16:54:03 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:03 volumio volumio[701]: info: CoreCommandRouter::volumioPushQueue Dec 27 16:54:03 volumio volumio[701]: info: Dec 27 16:54:03 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:03 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:03 volumio volumio[701]: error: updateQueue error: null Dec 27 16:54:03 volumio volumio[701]: info: ------------------------------ 2ms Dec 27 16:54:03 volumio volumio[701]: info: Starting UPNP Playback Dec 27 16:54:03 volumio volumio[701]: info: Preparing playback through UPNP Dec 27 16:54:03 volumio volumio[701]: info: CoreCommandRouter::volumioGetState Dec 27 16:54:03 volumio volumio[701]: info: CorePlayQueue::getTrack 0 Dec 27 16:54:03 volumio volumio[701]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 27 16:54:04 volumio volumio[701]: info: Dec 27 16:54:04 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:04 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:04 volumio volumio[701]: info: ------------------------------ 1ms Dec 27 16:54:04 volumio volumio[701]: info: Dec 27 16:54:04 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:04 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:04 volumio volumio[701]: info: ------------------------------ 2ms Dec 27 16:54:04 volumio volumio[701]: info: Dec 27 16:54:04 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:04 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:04 volumio volumio[701]: info: ------------------------------ 1ms Dec 27 16:54:04 volumio volumio[701]: info: Dec 27 16:54:04 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:04 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:04 volumio volumio[701]: info: ------------------------------ 2ms Dec 27 16:54:04 volumio volumio[701]: info: Dec 27 16:54:04 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:04 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:04 volumio volumio[701]: info: ------------------------------ 1ms Dec 27 16:54:04 volumio volumio[701]: info: Dec 27 16:54:04 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:04 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:04 volumio volumio[701]: info: ------------------------------ 2ms Dec 27 16:54:04 volumio volumio[701]: info: Dec 27 16:54:04 volumio volumio[701]: ---------------------------- MPD announces state update: player Dec 27 16:54:04 volumio volumio[701]: info: ControllerMpd::getState Dec 27 16:54:04 volumio volumio[701]: verbose: ControllerMpd::sendMpdCommand status Dec 27 16:54:04 volumio volumio[701]: info: sendMpdCommand status took 1 milliseconds Dec 27 16:54:04 volumio volumio[701]: verbose: ControllerMpd::parseState Dec 27 16:54:04 volumio volumio[701]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 16:54:04 volumio volumio[701]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 27 16:54:04 volumio volumio[701]: verbose: ControllerMpd::parseTrackInfo Dec 27 16:54:04 volumio volumio[701]: info: ControllerMpd::pushState Dec 27 16:54:04 volumio volumio[701]: info: CoreCommandRouter::servicePushState Dec 27 16:54:04 volumio volumio[701]: info: CoreStateMachine::pushState Dec 27 16:54:04 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:04 volumio volumio[701]: info: CorePlayQueue::getTrack 0 Dec 27 16:54:04 volumio volumio[701]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"当我想你的时候","artist":"丽江小倩","album":"温暖你","uri":"http://aqqmusic.tc.qq.com/M800001uJ3BC4WxtuK.mp3?guid=000000005307aef90000019af43fe5cf&vkey=1AD770804E20A6E834628135F307CC626364F033F41557A1A6DE5679515CFE84737BA42EFAE915169E866C5054C8E4F45E98707FC8345C2D__v2b9abf9e&uin=905090038&redirect=1&fromtag=111093","trackType":""} Dec 27 16:54:04 volumio volumio[701]: verbose: CURRENT POSITION 0 Dec 27 16:54:04 volumio volumio[701]: info: CoreStateMachine::syncState stateService stop Dec 27 16:54:04 volumio volumio[701]: info: CoreStateMachine::syncState currentStatus stop Dec 27 16:54:04 volumio volumio[701]: info: CoreStateMachine::pushState Dec 27 16:54:04 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:04 volumio volumio[701]: info: No code Dec 27 16:54:04 volumio volumio[701]: info: CoreStateMachine::pushState Dec 27 16:54:04 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:04 volumio volumio[701]: info: ------------------------------ 15ms Dec 27 16:54:04 volumio volumio[701]: info: CoreCommandRouter::volumioGetQueue Dec 27 16:54:04 volumio volumio[701]: info: CoreStateMachine::getQueue Dec 27 16:54:04 volumio volumio[701]: info: CorePlayQueue::getQueue Dec 27 16:54:04 volumio volumio[701]: info: CoreCommandRouter::volumioGetQueue Dec 27 16:54:04 volumio volumio[701]: info: CoreStateMachine::getQueue Dec 27 16:54:04 volumio volumio[701]: info: CorePlayQueue::getQueue Dec 27 16:54:04 volumio volumio[701]: info: CoreCommandRouter::volumioGetQueue Dec 27 16:54:04 volumio volumio[701]: info: CoreStateMachine::getQueue Dec 27 16:54:04 volumio volumio[701]: info: CorePlayQueue::getQueue Dec 27 16:54:04 volumio volumio[701]: info: Clearing queue after UPNP request Dec 27 16:54:04 volumio volumio[701]: info: CoreStateMachine::ClearQueue Dec 27 16:54:04 volumio volumio[701]: info: CoreStateMachine::stop Dec 27 16:54:04 volumio volumio[701]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 16:54:04 volumio volumio[701]: info: CorePlayQueue::clearPlayQueue Dec 27 16:54:04 volumio volumio[701]: info: CorePlayQueue::saveQueue Dec 27 16:54:04 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:04 volumio volumio[701]: info: CoreCommandRouter::volumioPushQueue Dec 27 16:54:04 volumio volumio[701]: info: Dec 27 16:54:04 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:04 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:04 volumio volumio[701]: error: updateQueue error: null Dec 27 16:54:04 volumio volumio[701]: info: ------------------------------ 1ms Dec 27 16:54:04 volumio volumio[701]: info: Starting UPNP Playback Dec 27 16:54:04 volumio volumio[701]: info: Preparing playback through UPNP Dec 27 16:54:04 volumio volumio[701]: info: CoreCommandRouter::volumioGetState Dec 27 16:54:04 volumio volumio[701]: info: CorePlayQueue::getTrack 0 Dec 27 16:54:04 volumio volumio[701]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 27 16:54:05 volumio volumio[701]: info: Dec 27 16:54:05 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:05 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:05 volumio volumio[701]: info: ------------------------------ 4ms Dec 27 16:54:05 volumio volumio[701]: info: Dec 27 16:54:05 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:05 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:05 volumio volumio[701]: info: ------------------------------ 2ms Dec 27 16:54:05 volumio volumio[701]: info: Dec 27 16:54:05 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:05 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:05 volumio volumio[701]: info: ------------------------------ 2ms Dec 27 16:54:05 volumio volumio[701]: info: Dec 27 16:54:05 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:05 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:05 volumio volumio[701]: info: ------------------------------ 1ms Dec 27 16:54:05 volumio volumio[701]: info: Dec 27 16:54:05 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:05 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:05 volumio volumio[701]: info: ------------------------------ 2ms Dec 27 16:54:05 volumio volumio[701]: info: Dec 27 16:54:05 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:05 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:05 volumio volumio[701]: info: ------------------------------ 2ms Dec 27 16:54:05 volumio volumio[701]: info: Dec 27 16:54:05 volumio volumio[701]: ---------------------------- MPD announces state update: player Dec 27 16:54:05 volumio volumio[701]: info: ControllerMpd::getState Dec 27 16:54:05 volumio volumio[701]: verbose: ControllerMpd::sendMpdCommand status Dec 27 16:54:05 volumio volumio[701]: info: sendMpdCommand status took 1 milliseconds Dec 27 16:54:05 volumio volumio[701]: verbose: ControllerMpd::parseState Dec 27 16:54:05 volumio volumio[701]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 16:54:05 volumio volumio[701]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 27 16:54:05 volumio volumio[701]: verbose: ControllerMpd::parseTrackInfo Dec 27 16:54:05 volumio volumio[701]: info: ControllerMpd::pushState Dec 27 16:54:05 volumio volumio[701]: info: CoreCommandRouter::servicePushState Dec 27 16:54:05 volumio volumio[701]: info: CoreStateMachine::pushState Dec 27 16:54:05 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:05 volumio volumio[701]: info: CorePlayQueue::getTrack 0 Dec 27 16:54:05 volumio volumio[701]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"蝴蝶结","artist":"丽江小倩","album":"温暖你","uri":"http://aqqmusic.tc.qq.com/M800002s7ard4AzVbj.mp3?guid=000000005307aef90000019af43fe5cf&vkey=CFA7D4F43A1356E274964A27C6A2359A01AAB768120F7EFC5A5A184E2DFDE5EA277AD62B526BF064D0EE0475DB301DD8AD527CE0C03A3B7B__v2b9ab708&uin=905090038&redirect=1&fromtag=111093","trackType":""} Dec 27 16:54:05 volumio volumio[701]: verbose: CURRENT POSITION 0 Dec 27 16:54:05 volumio volumio[701]: info: CoreStateMachine::syncState stateService stop Dec 27 16:54:05 volumio volumio[701]: info: CoreStateMachine::syncState currentStatus stop Dec 27 16:54:05 volumio volumio[701]: info: CoreStateMachine::pushState Dec 27 16:54:05 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:05 volumio volumio[701]: info: No code Dec 27 16:54:05 volumio volumio[701]: info: CoreStateMachine::pushState Dec 27 16:54:05 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:05 volumio volumio[701]: info: ------------------------------ 12ms Dec 27 16:54:05 volumio volumio[701]: info: CoreCommandRouter::volumioGetQueue Dec 27 16:54:05 volumio volumio[701]: info: CoreStateMachine::getQueue Dec 27 16:54:05 volumio volumio[701]: info: CorePlayQueue::getQueue Dec 27 16:54:05 volumio volumio[701]: info: CoreCommandRouter::volumioGetQueue Dec 27 16:54:05 volumio volumio[701]: info: CoreStateMachine::getQueue Dec 27 16:54:05 volumio volumio[701]: info: CorePlayQueue::getQueue Dec 27 16:54:05 volumio volumio[701]: info: CoreCommandRouter::volumioGetQueue Dec 27 16:54:05 volumio volumio[701]: info: CoreStateMachine::getQueue Dec 27 16:54:05 volumio volumio[701]: info: CorePlayQueue::getQueue Dec 27 16:54:05 volumio volumio[701]: info: Clearing queue after UPNP request Dec 27 16:54:06 volumio volumio[701]: info: CoreStateMachine::ClearQueue Dec 27 16:54:06 volumio volumio[701]: info: CoreStateMachine::stop Dec 27 16:54:06 volumio volumio[701]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 16:54:06 volumio volumio[701]: info: CorePlayQueue::clearPlayQueue Dec 27 16:54:06 volumio volumio[701]: info: CorePlayQueue::saveQueue Dec 27 16:54:06 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:06 volumio volumio[701]: info: CoreCommandRouter::volumioPushQueue Dec 27 16:54:06 volumio volumio[701]: info: Dec 27 16:54:06 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:06 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:06 volumio volumio[701]: error: updateQueue error: null Dec 27 16:54:06 volumio volumio[701]: info: ------------------------------ 2ms Dec 27 16:54:06 volumio volumio[701]: info: Starting UPNP Playback Dec 27 16:54:06 volumio volumio[701]: info: Preparing playback through UPNP Dec 27 16:54:06 volumio volumio[701]: info: CoreCommandRouter::volumioGetState Dec 27 16:54:06 volumio volumio[701]: info: CorePlayQueue::getTrack 0 Dec 27 16:54:06 volumio volumio[701]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 27 16:54:06 volumio volumio[701]: info: Dec 27 16:54:06 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:06 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:06 volumio volumio[701]: info: ------------------------------ 6ms Dec 27 16:54:06 volumio volumio[701]: info: Dec 27 16:54:06 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:06 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:06 volumio volumio[701]: info: ------------------------------ 1ms Dec 27 16:54:06 volumio volumio[701]: info: Dec 27 16:54:06 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:06 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:06 volumio volumio[701]: info: ------------------------------ 2ms Dec 27 16:54:06 volumio volumio[701]: info: Dec 27 16:54:06 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:06 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:06 volumio volumio[701]: info: ------------------------------ 1ms Dec 27 16:54:06 volumio volumio[701]: info: Dec 27 16:54:06 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:06 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:06 volumio volumio[701]: info: ------------------------------ 1ms Dec 27 16:54:06 volumio volumio[701]: info: Dec 27 16:54:06 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:06 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:06 volumio volumio[701]: info: ------------------------------ 2ms Dec 27 16:54:07 volumio volumio[701]: info: Dec 27 16:54:07 volumio volumio[701]: ---------------------------- MPD announces state update: player Dec 27 16:54:07 volumio volumio[701]: info: ControllerMpd::getState Dec 27 16:54:07 volumio volumio[701]: verbose: ControllerMpd::sendMpdCommand status Dec 27 16:54:07 volumio volumio[701]: info: Dec 27 16:54:07 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:07 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:07 volumio volumio[701]: info: Dec 27 16:54:07 volumio volumio[701]: ---------------------------- MPD announces state update: player Dec 27 16:54:07 volumio volumio[701]: info: sendMpdCommand status took 21 milliseconds Dec 27 16:54:07 volumio volumio[701]: info: ControllerMpd::getState Dec 27 16:54:07 volumio volumio[701]: verbose: ControllerMpd::sendMpdCommand status Dec 27 16:54:07 volumio volumio[701]: verbose: ControllerMpd::parseState Dec 27 16:54:07 volumio volumio[701]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 16:54:07 volumio volumio[701]: info: ------------------------------ 3ms Dec 27 16:54:07 volumio volumio[701]: info: sendMpdCommand status took 2 milliseconds Dec 27 16:54:07 volumio volumio[701]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 27 16:54:07 volumio volumio[701]: verbose: ControllerMpd::parseState Dec 27 16:54:07 volumio volumio[701]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 16:54:07 volumio volumio[701]: verbose: ControllerMpd::parseTrackInfo Dec 27 16:54:07 volumio volumio[701]: info: ControllerMpd::pushState Dec 27 16:54:07 volumio volumio[701]: info: CoreCommandRouter::servicePushState Dec 27 16:54:07 volumio volumio[701]: verbose: In UPNP mode Dec 27 16:54:07 volumio volumio[701]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":296,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"我会想起你","artist":"丽江小倩","album":"温暖你","uri":"http://aqqmusic.tc.qq.com/M800003LEVK12j9LKD.mp3?guid=000000005307aef90000019af43fe5cf&vkey=758CF9B85F4CE24DDD3316D892681526ED4E8E09CC766DE432FFB1A9EFEFAC6CB877D043C6E52EB52A1173B86829910CAB299C63E4299B85__v2b9aae6a&uin=905090038&redirect=1&fromtag=111093","trackType":""} Dec 27 16:54:07 volumio volumio[701]: verbose: CURRENT POSITION 0 Dec 27 16:54:07 volumio volumio[701]: info: CoreStateMachine::syncState stateService play Dec 27 16:54:07 volumio volumio[701]: info: CoreStateMachine::syncState currentStatus stop Dec 27 16:54:07 volumio volumio[701]: info: ------------------------------ 27ms Dec 27 16:54:07 volumio volumio[701]: info: sendMpdCommand playlistinfo took 5 milliseconds Dec 27 16:54:07 volumio volumio[701]: verbose: ControllerMpd::parseTrackInfo Dec 27 16:54:07 volumio volumio[701]: info: ControllerMpd::pushState Dec 27 16:54:07 volumio volumio[701]: info: CoreCommandRouter::servicePushState Dec 27 16:54:07 volumio volumio[701]: verbose: In UPNP mode Dec 27 16:54:07 volumio volumio[701]: verbose: STATE SERVICE {"status":"play","position":0,"seek":584,"duration":296,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"我会想起你","artist":"丽江小倩","album":"温暖你","uri":"http://aqqmusic.tc.qq.com/M800003LEVK12j9LKD.mp3?guid=000000005307aef90000019af43fe5cf&vkey=758CF9B85F4CE24DDD3316D892681526ED4E8E09CC766DE432FFB1A9EFEFAC6CB877D043C6E52EB52A1173B86829910CAB299C63E4299B85__v2b9aae6a&uin=905090038&redirect=1&fromtag=111093","trackType":""} Dec 27 16:54:07 volumio volumio[701]: verbose: CURRENT POSITION 0 Dec 27 16:54:07 volumio volumio[701]: info: CoreStateMachine::syncState stateService play Dec 27 16:54:07 volumio volumio[701]: info: CoreStateMachine::syncState currentStatus play Dec 27 16:54:07 volumio volumio[701]: info: Received an update from plugin. extracting info from payload Dec 27 16:54:07 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 27 16:54:07 volumio volumio[701]: info: CoreStateMachine::pushState Dec 27 16:54:07 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 16:54:07 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:07 volumio volumio[701]: info: CoreStateMachine::pushState Dec 27 16:54:07 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:07 volumio volumio[701]: info: ------------------------------ 18ms Dec 27 16:54:07 volumio volumio[701]: info: CoreCommandRouter::volumioGetQueue Dec 27 16:54:07 volumio volumio[701]: info: CoreStateMachine::getQueue Dec 27 16:54:07 volumio volumio[701]: info: CorePlayQueue::getQueue Dec 27 16:54:07 volumio volumio[701]: info: CoreCommandRouter::volumioGetQueue Dec 27 16:54:07 volumio volumio[701]: info: CoreStateMachine::getQueue Dec 27 16:54:07 volumio volumio[701]: info: CorePlayQueue::getQueue Dec 27 16:54:08 volumio kernel: hwmon hwmon1: Undervoltage detected! Dec 27 16:54:09 volumio volumio[966]: 34 Dec 27 16:54:09 volumio volumio[966]: % Total % Received % Xferd Average Speed Time Time Time Current Dec 27 16:54:09 volumio volumio[966]: Dload Upload Total Spent Left Speed Dec 27 16:54:09 volumio volumio[701]: info: Dec 27 16:54:09 volumio volumio[701]: ---------------------------- Client requests Volume 34 Dec 27 16:54:09 volumio volumio[701]: info: VolumeController::SetAlsaVolume34 Dec 27 16:54:09 volumio volumio[701]: info: CoreStateMachine::pushState Dec 27 16:54:09 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 16:54:09 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:09 volumio volumio[966]: [158B blob data] Dec 27 16:54:09 volumio volumio[701]: info: CoreCommandRouter::volumioGetQueue Dec 27 16:54:09 volumio volumio[701]: info: CoreStateMachine::getQueue Dec 27 16:54:09 volumio volumio[701]: info: CorePlayQueue::getQueue Dec 27 16:54:09 volumio volumio[966]: {"time":1766825649869,"response":"volume Success"}34 Dec 27 16:54:10 volumio volumio[966]: 24 Dec 27 16:54:10 volumio volumio[966]: % Total % Received % Xferd Average Speed Time Time Time Current Dec 27 16:54:10 volumio volumio[966]: Dload Upload Total Spent Left Speed Dec 27 16:54:10 volumio volumio[701]: info: Dec 27 16:54:10 volumio volumio[701]: ---------------------------- Client requests Volume 24 Dec 27 16:54:10 volumio volumio[701]: info: VolumeController::SetAlsaVolume24 Dec 27 16:54:10 volumio volumio[701]: info: CoreStateMachine::pushState Dec 27 16:54:10 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 16:54:10 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:10 volumio volumio[966]: [237B blob data] Dec 27 16:54:10 volumio volumio[701]: info: CoreCommandRouter::volumioGetQueue Dec 27 16:54:10 volumio volumio[701]: info: CoreStateMachine::getQueue Dec 27 16:54:10 volumio volumio[701]: info: CorePlayQueue::getQueue Dec 27 16:54:10 volumio volumio[966]: {"time":1766825650292,"response":"volume Success"}24 Dec 27 16:54:11 volumio volumio[966]: 34 Dec 27 16:54:11 volumio volumio[966]: % Total % Received % Xferd Average Speed Time Time Time Current Dec 27 16:54:11 volumio volumio[966]: Dload Upload Total Spent Left Speed Dec 27 16:54:11 volumio volumio[701]: info: Dec 27 16:54:11 volumio volumio[701]: ---------------------------- Client requests Volume 34 Dec 27 16:54:11 volumio volumio[701]: info: VolumeController::SetAlsaVolume34 Dec 27 16:54:11 volumio volumio[701]: info: CoreStateMachine::pushState Dec 27 16:54:11 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 16:54:11 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:11 volumio volumio[966]: [158B blob data] Dec 27 16:54:11 volumio volumio[966]: {"time":1766825651725,"response":"volume Success"}34 Dec 27 16:54:11 volumio volumio[701]: info: CoreCommandRouter::volumioGetQueue Dec 27 16:54:11 volumio volumio[701]: info: CoreStateMachine::getQueue Dec 27 16:54:11 volumio volumio[701]: info: CorePlayQueue::getQueue Dec 27 16:54:14 volumio kernel: hwmon hwmon1: Voltage normalised Dec 27 16:54:14 volumio volumio[701]: info: Dec 27 16:54:14 volumio volumio[701]: ---------------------------- MPD announces state update: player Dec 27 16:54:14 volumio volumio[701]: info: ControllerMpd::getState Dec 27 16:54:14 volumio volumio[701]: verbose: ControllerMpd::sendMpdCommand status Dec 27 16:54:14 volumio volumio[701]: info: sendMpdCommand status took 1 milliseconds Dec 27 16:54:14 volumio volumio[701]: verbose: ControllerMpd::parseState Dec 27 16:54:14 volumio volumio[701]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 16:54:14 volumio volumio[701]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 27 16:54:14 volumio volumio[701]: verbose: ControllerMpd::parseTrackInfo Dec 27 16:54:14 volumio volumio[701]: info: ControllerMpd::pushState Dec 27 16:54:14 volumio volumio[701]: info: CoreCommandRouter::servicePushState Dec 27 16:54:14 volumio volumio[701]: info: CoreStateMachine::pushState Dec 27 16:54:14 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:14 volumio volumio[701]: info: CorePlayQueue::getTrack 0 Dec 27 16:54:14 volumio volumio[701]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"我会想起你","artist":"丽江小倩","album":"温暖你","uri":"http://aqqmusic.tc.qq.com/M800003LEVK12j9LKD.mp3?guid=000000005307aef90000019af43fe5cf&vkey=758CF9B85F4CE24DDD3316D892681526ED4E8E09CC766DE432FFB1A9EFEFAC6CB877D043C6E52EB52A1173B86829910CAB299C63E4299B85__v2b9aae6a&uin=905090038&redirect=1&fromtag=111093","trackType":""} Dec 27 16:54:14 volumio volumio[701]: verbose: CURRENT POSITION 0 Dec 27 16:54:14 volumio volumio[701]: info: CoreStateMachine::syncState stateService stop Dec 27 16:54:14 volumio volumio[701]: info: CoreStateMachine::syncState currentStatus play Dec 27 16:54:14 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:14 volumio volumio[701]: info: CoreStateMachine::stPlaybackTimer Dec 27 16:54:14 volumio volumio[701]: info: ------------------------------ 10ms Dec 27 16:54:14 volumio volumio[701]: info: CoreCommandRouter::volumioGetQueue Dec 27 16:54:14 volumio volumio[701]: info: CoreStateMachine::getQueue Dec 27 16:54:14 volumio volumio[701]: info: CorePlayQueue::getQueue Dec 27 16:54:14 volumio volumio[701]: info: Clearing queue after UPNP request Dec 27 16:54:15 volumio volumio[701]: info: CoreStateMachine::ClearQueue Dec 27 16:54:15 volumio volumio[701]: info: CoreStateMachine::stop Dec 27 16:54:15 volumio volumio[701]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 16:54:15 volumio volumio[701]: info: CorePlayQueue::clearPlayQueue Dec 27 16:54:15 volumio volumio[701]: info: CorePlayQueue::saveQueue Dec 27 16:54:15 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:15 volumio volumio[701]: info: CoreCommandRouter::volumioPushQueue Dec 27 16:54:15 volumio volumio[701]: info: Dec 27 16:54:15 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:15 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:15 volumio volumio[701]: error: updateQueue error: null Dec 27 16:54:15 volumio volumio[701]: info: ------------------------------ 2ms Dec 27 16:54:15 volumio volumio[701]: info: Starting UPNP Playback Dec 27 16:54:15 volumio volumio[701]: info: Preparing playback through UPNP Dec 27 16:54:15 volumio volumio[701]: info: CoreCommandRouter::volumioGetState Dec 27 16:54:15 volumio volumio[701]: info: CorePlayQueue::getTrack 0 Dec 27 16:54:15 volumio volumio[701]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 27 16:54:15 volumio volumio[701]: info: Dec 27 16:54:15 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:15 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:15 volumio volumio[701]: info: ------------------------------ 1ms Dec 27 16:54:15 volumio volumio[701]: info: Dec 27 16:54:15 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:15 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:15 volumio volumio[701]: info: Dec 27 16:54:15 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:15 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:15 volumio volumio[701]: info: ------------------------------ 2ms Dec 27 16:54:15 volumio volumio[701]: info: ------------------------------ 1ms Dec 27 16:54:15 volumio volumio[701]: info: Dec 27 16:54:15 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:15 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:15 volumio volumio[701]: info: Dec 27 16:54:15 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:15 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:15 volumio volumio[701]: info: ------------------------------ 2ms Dec 27 16:54:15 volumio volumio[701]: info: ------------------------------ 1ms Dec 27 16:54:15 volumio volumio[701]: info: Dec 27 16:54:15 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:15 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:15 volumio volumio[701]: info: ------------------------------ 1ms Dec 27 16:54:15 volumio volumio[701]: info: Dec 27 16:54:15 volumio volumio[701]: ---------------------------- MPD announces state update: player Dec 27 16:54:15 volumio volumio[701]: info: ControllerMpd::getState Dec 27 16:54:15 volumio volumio[701]: verbose: ControllerMpd::sendMpdCommand status Dec 27 16:54:15 volumio volumio[701]: info: Dec 27 16:54:15 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:15 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:15 volumio volumio[701]: info: Dec 27 16:54:15 volumio volumio[701]: ---------------------------- MPD announces state update: player Dec 27 16:54:15 volumio volumio[701]: info: sendMpdCommand status took 28 milliseconds Dec 27 16:54:15 volumio volumio[701]: info: ControllerMpd::getState Dec 27 16:54:15 volumio volumio[701]: verbose: ControllerMpd::sendMpdCommand status Dec 27 16:54:15 volumio volumio[701]: verbose: ControllerMpd::parseState Dec 27 16:54:15 volumio volumio[701]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 16:54:15 volumio volumio[701]: info: ------------------------------ 8ms Dec 27 16:54:15 volumio volumio[701]: info: sendMpdCommand status took 3 milliseconds Dec 27 16:54:15 volumio volumio[701]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 27 16:54:15 volumio volumio[701]: verbose: ControllerMpd::parseState Dec 27 16:54:15 volumio volumio[701]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 16:54:15 volumio volumio[701]: verbose: ControllerMpd::parseTrackInfo Dec 27 16:54:15 volumio volumio[701]: info: ControllerMpd::pushState Dec 27 16:54:15 volumio volumio[701]: info: CoreCommandRouter::servicePushState Dec 27 16:54:15 volumio volumio[701]: verbose: In UPNP mode Dec 27 16:54:15 volumio volumio[701]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":195,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"光芒","artist":"凤凰传奇","album":"大声唱","uri":"http://aqqmusic.tc.qq.com/M800000nhYLi3WWcXP.mp3?guid=000000005307aef90000019af43fe5cf&vkey=AA431DCDFA3A3CBA4E032EBE2EFF78C1F8E59D73E5900355BE33A45C8E05D769F00E83915E10EA192CB3ED77CDCFE45F51CFDBAF32BB5090__v2b9abf9e&uin=905090038&redirect=1&fromtag=111093","trackType":""} Dec 27 16:54:15 volumio volumio[701]: verbose: CURRENT POSITION 0 Dec 27 16:54:15 volumio volumio[701]: info: CoreStateMachine::syncState stateService play Dec 27 16:54:15 volumio volumio[701]: info: CoreStateMachine::syncState currentStatus stop Dec 27 16:54:15 volumio volumio[701]: info: ------------------------------ 35ms Dec 27 16:54:15 volumio volumio[701]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 27 16:54:15 volumio volumio[701]: verbose: ControllerMpd::parseTrackInfo Dec 27 16:54:15 volumio volumio[701]: info: ControllerMpd::pushState Dec 27 16:54:15 volumio volumio[701]: info: CoreCommandRouter::servicePushState Dec 27 16:54:15 volumio volumio[701]: verbose: In UPNP mode Dec 27 16:54:15 volumio volumio[701]: verbose: STATE SERVICE {"status":"play","position":0,"seek":881,"duration":195,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"光芒","artist":"凤凰传奇","album":"大声唱","uri":"http://aqqmusic.tc.qq.com/M800000nhYLi3WWcXP.mp3?guid=000000005307aef90000019af43fe5cf&vkey=AA431DCDFA3A3CBA4E032EBE2EFF78C1F8E59D73E5900355BE33A45C8E05D769F00E83915E10EA192CB3ED77CDCFE45F51CFDBAF32BB5090__v2b9abf9e&uin=905090038&redirect=1&fromtag=111093","trackType":""} Dec 27 16:54:15 volumio volumio[701]: verbose: CURRENT POSITION 0 Dec 27 16:54:15 volumio volumio[701]: info: CoreStateMachine::syncState stateService play Dec 27 16:54:15 volumio volumio[701]: info: CoreStateMachine::syncState currentStatus play Dec 27 16:54:15 volumio volumio[701]: info: Received an update from plugin. extracting info from payload Dec 27 16:54:15 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 27 16:54:15 volumio volumio[701]: info: CoreStateMachine::pushState Dec 27 16:54:15 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 16:54:15 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:15 volumio volumio[701]: info: CoreStateMachine::pushState Dec 27 16:54:15 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:15 volumio volumio[701]: info: ------------------------------ 23ms Dec 27 16:54:15 volumio volumio[701]: info: CoreCommandRouter::volumioGetQueue Dec 27 16:54:15 volumio volumio[701]: info: CoreStateMachine::getQueue Dec 27 16:54:15 volumio volumio[701]: info: CorePlayQueue::getQueue Dec 27 16:54:15 volumio volumio[701]: info: CoreCommandRouter::volumioGetQueue Dec 27 16:54:15 volumio volumio[701]: info: CoreStateMachine::getQueue Dec 27 16:54:15 volumio volumio[701]: info: CorePlayQueue::getQueue Dec 27 16:54:15 volumio volumio[701]: info: Dec 27 16:54:15 volumio volumio[701]: ---------------------------- MPD announces state update: player Dec 27 16:54:15 volumio volumio[701]: info: ControllerMpd::getState Dec 27 16:54:15 volumio volumio[701]: verbose: ControllerMpd::sendMpdCommand status Dec 27 16:54:15 volumio volumio[701]: info: sendMpdCommand status took 0 milliseconds Dec 27 16:54:15 volumio volumio[701]: verbose: ControllerMpd::parseState Dec 27 16:54:15 volumio volumio[701]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 16:54:15 volumio volumio[701]: info: sendMpdCommand playlistinfo took 0 milliseconds Dec 27 16:54:15 volumio volumio[701]: verbose: ControllerMpd::parseTrackInfo Dec 27 16:54:15 volumio volumio[701]: info: ControllerMpd::pushState Dec 27 16:54:15 volumio volumio[701]: info: CoreCommandRouter::servicePushState Dec 27 16:54:15 volumio volumio[701]: info: CoreStateMachine::pushState Dec 27 16:54:15 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:15 volumio volumio[701]: info: CorePlayQueue::getTrack 0 Dec 27 16:54:15 volumio volumio[701]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"光芒","artist":"凤凰传奇","album":"大声唱","uri":"http://aqqmusic.tc.qq.com/M800000nhYLi3WWcXP.mp3?guid=000000005307aef90000019af43fe5cf&vkey=AA431DCDFA3A3CBA4E032EBE2EFF78C1F8E59D73E5900355BE33A45C8E05D769F00E83915E10EA192CB3ED77CDCFE45F51CFDBAF32BB5090__v2b9abf9e&uin=905090038&redirect=1&fromtag=111093","trackType":""} Dec 27 16:54:15 volumio volumio[701]: verbose: CURRENT POSITION 0 Dec 27 16:54:15 volumio volumio[701]: info: CoreStateMachine::syncState stateService stop Dec 27 16:54:15 volumio volumio[701]: info: CoreStateMachine::syncState currentStatus play Dec 27 16:54:15 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:15 volumio volumio[701]: info: CoreStateMachine::stPlaybackTimer Dec 27 16:54:15 volumio volumio[701]: info: ------------------------------ 9ms Dec 27 16:54:15 volumio volumio[701]: info: CoreCommandRouter::volumioGetQueue Dec 27 16:54:15 volumio volumio[701]: info: CoreStateMachine::getQueue Dec 27 16:54:15 volumio volumio[701]: info: CorePlayQueue::getQueue Dec 27 16:54:15 volumio volumio[701]: info: Clearing queue after UPNP request Dec 27 16:54:16 volumio volumio[701]: info: CoreStateMachine::ClearQueue Dec 27 16:54:16 volumio volumio[701]: info: CoreStateMachine::stop Dec 27 16:54:16 volumio volumio[701]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 16:54:16 volumio volumio[701]: info: CorePlayQueue::clearPlayQueue Dec 27 16:54:16 volumio volumio[701]: info: CorePlayQueue::saveQueue Dec 27 16:54:16 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:16 volumio volumio[701]: info: CoreCommandRouter::volumioPushQueue Dec 27 16:54:16 volumio volumio[701]: info: Dec 27 16:54:16 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:16 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:16 volumio volumio[701]: error: updateQueue error: null Dec 27 16:54:16 volumio volumio[701]: info: ------------------------------ 2ms Dec 27 16:54:16 volumio volumio[701]: info: Starting UPNP Playback Dec 27 16:54:16 volumio volumio[701]: info: Preparing playback through UPNP Dec 27 16:54:16 volumio volumio[701]: info: CoreCommandRouter::volumioGetState Dec 27 16:54:16 volumio volumio[701]: info: CorePlayQueue::getTrack 0 Dec 27 16:54:16 volumio volumio[701]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 27 16:54:16 volumio volumio[701]: info: Dec 27 16:54:16 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:16 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:16 volumio volumio[701]: info: Dec 27 16:54:16 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:16 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:16 volumio volumio[701]: info: ------------------------------ 2ms Dec 27 16:54:16 volumio volumio[701]: info: ------------------------------ 1ms Dec 27 16:54:16 volumio volumio[701]: info: Dec 27 16:54:16 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:16 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:16 volumio volumio[701]: info: Dec 27 16:54:16 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:16 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:16 volumio volumio[701]: info: ------------------------------ 2ms Dec 27 16:54:16 volumio volumio[701]: info: ------------------------------ 1ms Dec 27 16:54:16 volumio volumio[701]: info: Dec 27 16:54:16 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:16 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:16 volumio volumio[701]: info: ------------------------------ 1ms Dec 27 16:54:16 volumio volumio[701]: info: Dec 27 16:54:16 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:16 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:16 volumio volumio[701]: info: ------------------------------ 1ms Dec 27 16:54:16 volumio volumio[701]: info: Dec 27 16:54:16 volumio volumio[701]: ---------------------------- MPD announces state update: player Dec 27 16:54:16 volumio volumio[701]: info: ControllerMpd::getState Dec 27 16:54:16 volumio volumio[701]: verbose: ControllerMpd::sendMpdCommand status Dec 27 16:54:16 volumio volumio[701]: info: sendMpdCommand status took 1 milliseconds Dec 27 16:54:16 volumio volumio[701]: verbose: ControllerMpd::parseState Dec 27 16:54:16 volumio volumio[701]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 16:54:16 volumio volumio[701]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 27 16:54:16 volumio volumio[701]: verbose: ControllerMpd::parseTrackInfo Dec 27 16:54:16 volumio volumio[701]: info: ControllerMpd::pushState Dec 27 16:54:16 volumio volumio[701]: info: CoreCommandRouter::servicePushState Dec 27 16:54:16 volumio volumio[701]: info: CoreStateMachine::pushState Dec 27 16:54:16 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 16:54:16 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:16 volumio volumio[701]: info: CorePlayQueue::getTrack 0 Dec 27 16:54:16 volumio volumio[701]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"绿旋风","artist":"凤凰传奇","album":"凤凰传奇原创合集","uri":"http://aqqmusic.tc.qq.com/M800001aIupY0qyVKC.mp3?guid=000000005307aef90000019af43fe5cf&vkey=967D9EB44448C5D86A4BFC22F722F1D738441FBD79CF2D391944B792B160147EF4F89399E71E7917F186CA212F5CBD02528F27993B93D1F1__v2ba82048&uin=905090038&redirect=1&fromtag=111093","trackType":""} Dec 27 16:54:16 volumio volumio[701]: verbose: CURRENT POSITION 0 Dec 27 16:54:16 volumio volumio[701]: info: CoreStateMachine::syncState stateService stop Dec 27 16:54:16 volumio volumio[701]: info: CoreStateMachine::syncState currentStatus stop Dec 27 16:54:16 volumio volumio[701]: info: CoreStateMachine::pushState Dec 27 16:54:16 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:16 volumio volumio[701]: info: No code Dec 27 16:54:16 volumio volumio[701]: info: CoreStateMachine::pushState Dec 27 16:54:16 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:16 volumio volumio[701]: info: ------------------------------ 11ms Dec 27 16:54:16 volumio volumio[701]: info: Clearing queue after UPNP request Dec 27 16:54:17 volumio volumio[701]: info: CoreStateMachine::ClearQueue Dec 27 16:54:17 volumio volumio[701]: info: CoreStateMachine::stop Dec 27 16:54:17 volumio volumio[701]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 16:54:17 volumio volumio[701]: info: CorePlayQueue::clearPlayQueue Dec 27 16:54:17 volumio volumio[701]: info: CorePlayQueue::saveQueue Dec 27 16:54:17 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:17 volumio volumio[701]: info: CoreCommandRouter::volumioPushQueue Dec 27 16:54:17 volumio volumio[701]: info: Dec 27 16:54:17 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:17 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:17 volumio volumio[701]: error: updateQueue error: null Dec 27 16:54:17 volumio volumio[701]: info: ------------------------------ 1ms Dec 27 16:54:17 volumio volumio[701]: info: Starting UPNP Playback Dec 27 16:54:17 volumio volumio[701]: info: Preparing playback through UPNP Dec 27 16:54:17 volumio volumio[701]: info: CoreCommandRouter::volumioGetState Dec 27 16:54:17 volumio volumio[701]: info: CorePlayQueue::getTrack 0 Dec 27 16:54:17 volumio volumio[701]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 27 16:54:17 volumio volumio[701]: info: Dec 27 16:54:17 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:17 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:17 volumio volumio[701]: info: Dec 27 16:54:17 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:17 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:17 volumio volumio[701]: info: ------------------------------ 2ms Dec 27 16:54:17 volumio volumio[701]: info: ------------------------------ 2ms Dec 27 16:54:17 volumio volumio[701]: info: Dec 27 16:54:17 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:17 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:17 volumio volumio[701]: info: Dec 27 16:54:17 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:17 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:17 volumio volumio[701]: info: ------------------------------ 1ms Dec 27 16:54:17 volumio volumio[701]: info: ------------------------------ 1ms Dec 27 16:54:17 volumio volumio[701]: info: Dec 27 16:54:17 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:17 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:17 volumio volumio[701]: info: ------------------------------ 1ms Dec 27 16:54:17 volumio volumio[701]: info: Dec 27 16:54:17 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:17 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:17 volumio volumio[701]: info: ------------------------------ 1ms Dec 27 16:54:17 volumio volumio[701]: info: Dec 27 16:54:17 volumio volumio[701]: ---------------------------- MPD announces state update: player Dec 27 16:54:17 volumio volumio[701]: info: ControllerMpd::getState Dec 27 16:54:17 volumio volumio[701]: verbose: ControllerMpd::sendMpdCommand status Dec 27 16:54:17 volumio volumio[701]: info: sendMpdCommand status took 1 milliseconds Dec 27 16:54:17 volumio volumio[701]: verbose: ControllerMpd::parseState Dec 27 16:54:17 volumio volumio[701]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 16:54:17 volumio volumio[701]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 27 16:54:17 volumio volumio[701]: verbose: ControllerMpd::parseTrackInfo Dec 27 16:54:17 volumio volumio[701]: info: ControllerMpd::pushState Dec 27 16:54:17 volumio volumio[701]: info: CoreCommandRouter::servicePushState Dec 27 16:54:17 volumio volumio[701]: info: CoreStateMachine::pushState Dec 27 16:54:17 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:17 volumio volumio[701]: info: CorePlayQueue::getTrack 0 Dec 27 16:54:17 volumio volumio[701]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"小小","artist":"容祖儿","album":"小小","uri":"http://aqqmusic.tc.qq.com/M800002PhRiv0U7n34.mp3?guid=000000005307aef90000019af43fe5cf&vkey=C0BCB6CAF8B5419934C3399CB3A5D1E685E8DDDD65A986886C9D9DD4FCCC2649C68D438B1B75EA1858416894E2817BA7F9111E79FE61176E__v2b9ab705&uin=905090038&redirect=1&fromtag=111093","trackType":""} Dec 27 16:54:17 volumio volumio[701]: verbose: CURRENT POSITION 0 Dec 27 16:54:17 volumio volumio[701]: info: CoreStateMachine::syncState stateService stop Dec 27 16:54:17 volumio volumio[701]: info: CoreStateMachine::syncState currentStatus stop Dec 27 16:54:17 volumio volumio[701]: info: CoreStateMachine::pushState Dec 27 16:54:17 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:17 volumio volumio[701]: info: No code Dec 27 16:54:17 volumio volumio[701]: info: CoreStateMachine::pushState Dec 27 16:54:17 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:17 volumio volumio[701]: info: ------------------------------ 11ms Dec 27 16:54:17 volumio volumio[701]: info: Clearing queue after UPNP request Dec 27 16:54:18 volumio volumio[701]: info: CoreStateMachine::ClearQueue Dec 27 16:54:18 volumio volumio[701]: info: CoreStateMachine::stop Dec 27 16:54:18 volumio volumio[701]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 16:54:18 volumio volumio[701]: info: CorePlayQueue::clearPlayQueue Dec 27 16:54:18 volumio volumio[701]: info: CorePlayQueue::saveQueue Dec 27 16:54:18 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:18 volumio volumio[701]: info: CoreCommandRouter::volumioPushQueue Dec 27 16:54:18 volumio volumio[701]: info: Dec 27 16:54:18 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:18 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:18 volumio volumio[701]: error: updateQueue error: null Dec 27 16:54:18 volumio volumio[701]: info: ------------------------------ 1ms Dec 27 16:54:18 volumio volumio[701]: info: Starting UPNP Playback Dec 27 16:54:18 volumio volumio[701]: info: Preparing playback through UPNP Dec 27 16:54:18 volumio volumio[701]: info: CoreCommandRouter::volumioGetState Dec 27 16:54:18 volumio volumio[701]: info: CorePlayQueue::getTrack 0 Dec 27 16:54:18 volumio volumio[701]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 27 16:54:18 volumio volumio[701]: info: Dec 27 16:54:18 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:18 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:18 volumio volumio[701]: info: ------------------------------ 2ms Dec 27 16:54:18 volumio volumio[701]: info: Dec 27 16:54:18 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:18 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:18 volumio volumio[701]: info: ------------------------------ 1ms Dec 27 16:54:18 volumio volumio[701]: info: Dec 27 16:54:18 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:18 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:18 volumio volumio[701]: info: ------------------------------ 1ms Dec 27 16:54:18 volumio volumio[701]: info: Dec 27 16:54:18 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:18 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:18 volumio volumio[701]: info: Dec 27 16:54:18 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:18 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:18 volumio volumio[701]: info: ------------------------------ 2ms Dec 27 16:54:18 volumio volumio[701]: info: ------------------------------ 1ms Dec 27 16:54:18 volumio volumio[701]: info: Dec 27 16:54:18 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:18 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:18 volumio volumio[701]: info: ------------------------------ 2ms Dec 27 16:54:18 volumio volumio[701]: info: Dec 27 16:54:18 volumio volumio[701]: ---------------------------- MPD announces state update: player Dec 27 16:54:18 volumio volumio[701]: info: ControllerMpd::getState Dec 27 16:54:18 volumio volumio[701]: verbose: ControllerMpd::sendMpdCommand status Dec 27 16:54:18 volumio volumio[701]: info: sendMpdCommand status took 20 milliseconds Dec 27 16:54:18 volumio volumio[701]: verbose: ControllerMpd::parseState Dec 27 16:54:18 volumio volumio[701]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 16:54:18 volumio volumio[701]: info: Dec 27 16:54:18 volumio volumio[701]: ---------------------------- MPD announces state update: player Dec 27 16:54:18 volumio volumio[701]: info: ControllerMpd::getState Dec 27 16:54:18 volumio volumio[701]: verbose: ControllerMpd::sendMpdCommand status Dec 27 16:54:18 volumio volumio[701]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 27 16:54:18 volumio volumio[701]: verbose: ControllerMpd::parseTrackInfo Dec 27 16:54:18 volumio volumio[701]: info: ControllerMpd::pushState Dec 27 16:54:18 volumio volumio[701]: info: CoreCommandRouter::servicePushState Dec 27 16:54:18 volumio volumio[701]: verbose: In UPNP mode Dec 27 16:54:18 volumio volumio[701]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":245,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"那些你很冒险的梦","artist":"林俊杰","album":"学不会","uri":"http://aqqmusic.tc.qq.com/M800001d0uA21Z3ySv.mp3?guid=000000005307aef90000019af43fe5cf&vkey=05F5B62AC9074D1E97CA97710A87D0B139F93B8A835F87D2F1CE74BC3694F643E4CB404578341A4C1244DBA5B761D050797804FF454E0C63__v2b9ab709&uin=905090038&redirect=1&fromtag=111093","trackType":""} Dec 27 16:54:18 volumio volumio[701]: verbose: CURRENT POSITION 0 Dec 27 16:54:18 volumio volumio[701]: info: CoreStateMachine::syncState stateService play Dec 27 16:54:18 volumio volumio[701]: info: CoreStateMachine::syncState currentStatus stop Dec 27 16:54:18 volumio volumio[701]: info: ------------------------------ 33ms Dec 27 16:54:18 volumio volumio[701]: info: sendMpdCommand status took 12 milliseconds Dec 27 16:54:18 volumio volumio[701]: verbose: ControllerMpd::parseState Dec 27 16:54:18 volumio volumio[701]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 16:54:18 volumio volumio[701]: info: Dec 27 16:54:18 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:18 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:18 volumio volumio[701]: info: Dec 27 16:54:18 volumio volumio[701]: ---------------------------- MPD announces state update: player Dec 27 16:54:18 volumio volumio[701]: info: sendMpdCommand playlistinfo took 6 milliseconds Dec 27 16:54:18 volumio volumio[701]: info: ControllerMpd::getState Dec 27 16:54:18 volumio volumio[701]: verbose: ControllerMpd::sendMpdCommand status Dec 27 16:54:18 volumio volumio[701]: verbose: ControllerMpd::parseTrackInfo Dec 27 16:54:18 volumio volumio[701]: info: ControllerMpd::pushState Dec 27 16:54:18 volumio volumio[701]: info: CoreCommandRouter::servicePushState Dec 27 16:54:18 volumio volumio[701]: verbose: In UPNP mode Dec 27 16:54:18 volumio volumio[701]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":245,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"那些你很冒险的梦","artist":"林俊杰","album":"学不会","uri":"http://aqqmusic.tc.qq.com/M800001d0uA21Z3ySv.mp3?guid=000000005307aef90000019af43fe5cf&vkey=05F5B62AC9074D1E97CA97710A87D0B139F93B8A835F87D2F1CE74BC3694F643E4CB404578341A4C1244DBA5B761D050797804FF454E0C63__v2b9ab709&uin=905090038&redirect=1&fromtag=111093","trackType":""} Dec 27 16:54:18 volumio volumio[701]: verbose: CURRENT POSITION 0 Dec 27 16:54:18 volumio volumio[701]: info: CoreStateMachine::syncState stateService play Dec 27 16:54:18 volumio volumio[701]: info: CoreStateMachine::syncState currentStatus play Dec 27 16:54:18 volumio volumio[701]: info: Received an update from plugin. extracting info from payload Dec 27 16:54:18 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 27 16:54:18 volumio volumio[701]: info: CoreStateMachine::pushState Dec 27 16:54:18 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 16:54:18 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:18 volumio volumio[701]: info: CoreStateMachine::pushState Dec 27 16:54:18 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:18 volumio volumio[701]: info: ------------------------------ 34ms Dec 27 16:54:18 volumio volumio[701]: info: ------------------------------ 18ms Dec 27 16:54:18 volumio volumio[701]: info: sendMpdCommand status took 14 milliseconds Dec 27 16:54:18 volumio volumio[701]: verbose: ControllerMpd::parseState Dec 27 16:54:18 volumio volumio[701]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 16:54:18 volumio volumio[701]: info: CoreCommandRouter::volumioGetQueue Dec 27 16:54:18 volumio volumio[701]: info: CoreStateMachine::getQueue Dec 27 16:54:18 volumio volumio[701]: info: CorePlayQueue::getQueue Dec 27 16:54:18 volumio volumio[701]: info: CoreCommandRouter::volumioGetQueue Dec 27 16:54:18 volumio volumio[701]: info: CoreStateMachine::getQueue Dec 27 16:54:18 volumio volumio[701]: info: CorePlayQueue::getQueue Dec 27 16:54:18 volumio volumio[701]: info: sendMpdCommand playlistinfo took 6 milliseconds Dec 27 16:54:18 volumio volumio[701]: verbose: ControllerMpd::parseTrackInfo Dec 27 16:54:18 volumio volumio[701]: info: ControllerMpd::pushState Dec 27 16:54:18 volumio volumio[701]: info: CoreCommandRouter::servicePushState Dec 27 16:54:18 volumio volumio[701]: verbose: In UPNP mode Dec 27 16:54:18 volumio volumio[701]: verbose: STATE SERVICE {"status":"play","position":0,"seek":881,"duration":245,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"那些你很冒险的梦","artist":"林俊杰","album":"学不会","uri":"http://aqqmusic.tc.qq.com/M800001d0uA21Z3ySv.mp3?guid=000000005307aef90000019af43fe5cf&vkey=05F5B62AC9074D1E97CA97710A87D0B139F93B8A835F87D2F1CE74BC3694F643E4CB404578341A4C1244DBA5B761D050797804FF454E0C63__v2b9ab709&uin=905090038&redirect=1&fromtag=111093","trackType":""} Dec 27 16:54:18 volumio volumio[701]: verbose: CURRENT POSITION 0 Dec 27 16:54:18 volumio volumio[701]: info: CoreStateMachine::syncState stateService play Dec 27 16:54:18 volumio volumio[701]: info: CoreStateMachine::syncState currentStatus play Dec 27 16:54:18 volumio volumio[701]: info: Received an update from plugin. extracting info from payload Dec 27 16:54:18 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 27 16:54:18 volumio volumio[701]: info: CoreStateMachine::pushState Dec 27 16:54:18 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 16:54:18 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:18 volumio volumio[701]: info: CoreStateMachine::pushState Dec 27 16:54:18 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:18 volumio volumio[701]: info: ------------------------------ 38ms Dec 27 16:54:18 volumio volumio[701]: info: CoreCommandRouter::volumioGetQueue Dec 27 16:54:18 volumio volumio[701]: info: CoreStateMachine::getQueue Dec 27 16:54:18 volumio volumio[701]: info: CorePlayQueue::getQueue Dec 27 16:54:18 volumio volumio[701]: info: CoreCommandRouter::volumioGetQueue Dec 27 16:54:18 volumio volumio[701]: info: CoreStateMachine::getQueue Dec 27 16:54:18 volumio volumio[701]: info: CorePlayQueue::getQueue Dec 27 16:54:19 volumio volumio[701]: info: Dec 27 16:54:19 volumio volumio[701]: ---------------------------- MPD announces state update: player Dec 27 16:54:19 volumio volumio[701]: info: ControllerMpd::getState Dec 27 16:54:19 volumio volumio[701]: verbose: ControllerMpd::sendMpdCommand status Dec 27 16:54:19 volumio volumio[701]: info: sendMpdCommand status took 1 milliseconds Dec 27 16:54:19 volumio volumio[701]: verbose: ControllerMpd::parseState Dec 27 16:54:19 volumio volumio[701]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 16:54:19 volumio volumio[701]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 27 16:54:19 volumio volumio[701]: verbose: ControllerMpd::parseTrackInfo Dec 27 16:54:19 volumio volumio[701]: info: ControllerMpd::pushState Dec 27 16:54:19 volumio volumio[701]: info: CoreCommandRouter::servicePushState Dec 27 16:54:19 volumio volumio[701]: info: CoreStateMachine::pushState Dec 27 16:54:19 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:19 volumio volumio[701]: info: CorePlayQueue::getTrack 0 Dec 27 16:54:19 volumio volumio[701]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"那些你很冒险的梦","artist":"林俊杰","album":"学不会","uri":"http://aqqmusic.tc.qq.com/M800001d0uA21Z3ySv.mp3?guid=000000005307aef90000019af43fe5cf&vkey=05F5B62AC9074D1E97CA97710A87D0B139F93B8A835F87D2F1CE74BC3694F643E4CB404578341A4C1244DBA5B761D050797804FF454E0C63__v2b9ab709&uin=905090038&redirect=1&fromtag=111093","trackType":""} Dec 27 16:54:19 volumio volumio[701]: verbose: CURRENT POSITION 0 Dec 27 16:54:19 volumio volumio[701]: info: CoreStateMachine::syncState stateService stop Dec 27 16:54:19 volumio volumio[701]: info: CoreStateMachine::syncState currentStatus play Dec 27 16:54:19 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:19 volumio volumio[701]: info: CoreStateMachine::stPlaybackTimer Dec 27 16:54:19 volumio volumio[701]: info: ------------------------------ 28ms Dec 27 16:54:19 volumio volumio[701]: info: CoreCommandRouter::volumioGetQueue Dec 27 16:54:19 volumio volumio[701]: info: CoreStateMachine::getQueue Dec 27 16:54:19 volumio volumio[701]: info: CorePlayQueue::getQueue Dec 27 16:54:19 volumio volumio[701]: info: Clearing queue after UPNP request Dec 27 16:54:19 volumio volumio[701]: info: CoreStateMachine::ClearQueue Dec 27 16:54:19 volumio volumio[701]: info: CoreStateMachine::stop Dec 27 16:54:19 volumio volumio[701]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 16:54:19 volumio volumio[701]: info: CorePlayQueue::clearPlayQueue Dec 27 16:54:19 volumio volumio[701]: info: CorePlayQueue::saveQueue Dec 27 16:54:19 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:19 volumio volumio[701]: info: CoreCommandRouter::volumioPushQueue Dec 27 16:54:19 volumio volumio[701]: info: Dec 27 16:54:19 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:19 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:19 volumio volumio[701]: error: updateQueue error: null Dec 27 16:54:19 volumio volumio[701]: info: ------------------------------ 6ms Dec 27 16:54:19 volumio volumio[701]: info: Starting UPNP Playback Dec 27 16:54:19 volumio volumio[701]: info: Preparing playback through UPNP Dec 27 16:54:19 volumio volumio[701]: info: CoreCommandRouter::volumioGetState Dec 27 16:54:19 volumio volumio[701]: info: CorePlayQueue::getTrack 0 Dec 27 16:54:19 volumio volumio[701]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 27 16:54:19 volumio volumio[701]: info: Dec 27 16:54:19 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:19 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:19 volumio volumio[701]: info: ------------------------------ 5ms Dec 27 16:54:19 volumio volumio[701]: info: Dec 27 16:54:19 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:19 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:19 volumio volumio[701]: info: ------------------------------ 4ms Dec 27 16:54:19 volumio volumio[701]: info: Dec 27 16:54:19 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:19 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:19 volumio volumio[701]: info: ------------------------------ 4ms Dec 27 16:54:19 volumio volumio[701]: info: Dec 27 16:54:19 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:19 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:19 volumio volumio[701]: info: ------------------------------ 4ms Dec 27 16:54:19 volumio volumio[701]: info: Dec 27 16:54:19 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:19 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:19 volumio volumio[701]: info: ------------------------------ 4ms Dec 27 16:54:19 volumio volumio[701]: info: Dec 27 16:54:19 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:19 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:19 volumio volumio[701]: info: ------------------------------ 4ms Dec 27 16:54:20 volumio volumio[701]: info: Dec 27 16:54:20 volumio volumio[701]: ---------------------------- MPD announces state update: player Dec 27 16:54:20 volumio volumio[701]: info: ControllerMpd::getState Dec 27 16:54:20 volumio volumio[701]: verbose: ControllerMpd::sendMpdCommand status Dec 27 16:54:20 volumio volumio[701]: info: Dec 27 16:54:20 volumio volumio[701]: ---------------------------- MPD announces state update: player Dec 27 16:54:20 volumio volumio[701]: info: sendMpdCommand status took 47 milliseconds Dec 27 16:54:20 volumio volumio[701]: info: ControllerMpd::getState Dec 27 16:54:20 volumio volumio[701]: verbose: ControllerMpd::sendMpdCommand status Dec 27 16:54:20 volumio volumio[701]: verbose: ControllerMpd::parseState Dec 27 16:54:20 volumio volumio[701]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 16:54:20 volumio volumio[701]: info: Dec 27 16:54:20 volumio volumio[701]: ---------------------------- MPD announces state update: player Dec 27 16:54:20 volumio volumio[701]: info: ControllerMpd::getState Dec 27 16:54:20 volumio volumio[701]: verbose: ControllerMpd::sendMpdCommand status Dec 27 16:54:20 volumio volumio[701]: info: sendMpdCommand status took 4 milliseconds Dec 27 16:54:20 volumio volumio[701]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 27 16:54:20 volumio volumio[701]: verbose: ControllerMpd::parseState Dec 27 16:54:20 volumio volumio[701]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 16:54:20 volumio volumio[701]: verbose: ControllerMpd::parseTrackInfo Dec 27 16:54:20 volumio volumio[701]: info: ControllerMpd::pushState Dec 27 16:54:20 volumio volumio[701]: info: CoreCommandRouter::servicePushState Dec 27 16:54:20 volumio volumio[701]: verbose: In UPNP mode Dec 27 16:54:20 volumio volumio[701]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":272,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"三生三幸 (Live)","artist":"海来阿木/陈冰","album":"天赐的声音第三季 第10期","uri":"http://aqqmusic.tc.qq.com/M800001QImrk1eXebE.mp3?guid=000000005307aef90000019af43fe5cf&vkey=B1E1553F8918EE459F416DB308A2B893D6CAB8A95D27C6954E771750A2EAD8211C4175852ABC9D72137C88C31E102F407189C2028BFC6634__v2b9ab705&uin=905090038&redirect=1&fromtag=111093","trackType":""} Dec 27 16:54:20 volumio volumio[701]: verbose: CURRENT POSITION 0 Dec 27 16:54:20 volumio volumio[701]: info: CoreStateMachine::syncState stateService play Dec 27 16:54:20 volumio volumio[701]: info: CoreStateMachine::syncState currentStatus stop Dec 27 16:54:20 volumio volumio[701]: info: ------------------------------ 61ms Dec 27 16:54:20 volumio volumio[701]: info: sendMpdCommand status took 9 milliseconds Dec 27 16:54:20 volumio volumio[701]: info: sendMpdCommand playlistinfo took 8 milliseconds Dec 27 16:54:20 volumio volumio[701]: verbose: ControllerMpd::parseState Dec 27 16:54:20 volumio volumio[701]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 16:54:20 volumio volumio[701]: verbose: ControllerMpd::parseTrackInfo Dec 27 16:54:20 volumio volumio[701]: info: ControllerMpd::pushState Dec 27 16:54:20 volumio volumio[701]: info: CoreCommandRouter::servicePushState Dec 27 16:54:20 volumio volumio[701]: info: CoreStateMachine::pushState Dec 27 16:54:20 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 16:54:20 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:20 volumio volumio[701]: info: CorePlayQueue::getTrack 0 Dec 27 16:54:20 volumio volumio[701]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"三生三幸 (Live)","artist":"海来阿木/陈冰","album":"天赐的声音第三季 第10期","uri":"http://aqqmusic.tc.qq.com/M800001QImrk1eXebE.mp3?guid=000000005307aef90000019af43fe5cf&vkey=B1E1553F8918EE459F416DB308A2B893D6CAB8A95D27C6954E771750A2EAD8211C4175852ABC9D72137C88C31E102F407189C2028BFC6634__v2b9ab705&uin=905090038&redirect=1&fromtag=111093","trackType":""} Dec 27 16:54:20 volumio volumio[701]: verbose: CURRENT POSITION 0 Dec 27 16:54:20 volumio volumio[701]: info: CoreStateMachine::syncState stateService stop Dec 27 16:54:20 volumio volumio[701]: info: CoreStateMachine::syncState currentStatus play Dec 27 16:54:20 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:20 volumio volumio[701]: info: CoreStateMachine::stPlaybackTimer Dec 27 16:54:20 volumio volumio[701]: info: ------------------------------ 37ms Dec 27 16:54:20 volumio volumio[701]: info: sendMpdCommand playlistinfo took 22 milliseconds Dec 27 16:54:20 volumio volumio[701]: verbose: ControllerMpd::parseTrackInfo Dec 27 16:54:20 volumio volumio[701]: info: ControllerMpd::pushState Dec 27 16:54:20 volumio volumio[701]: info: CoreCommandRouter::servicePushState Dec 27 16:54:20 volumio volumio[701]: info: CoreStateMachine::pushState Dec 27 16:54:20 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 16:54:20 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:20 volumio volumio[701]: info: CorePlayQueue::getTrack 0 Dec 27 16:54:20 volumio volumio[701]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"三生三幸 (Live)","artist":"海来阿木/陈冰","album":"天赐的声音第三季 第10期","uri":"http://aqqmusic.tc.qq.com/M800001QImrk1eXebE.mp3?guid=000000005307aef90000019af43fe5cf&vkey=B1E1553F8918EE459F416DB308A2B893D6CAB8A95D27C6954E771750A2EAD8211C4175852ABC9D72137C88C31E102F407189C2028BFC6634__v2b9ab705&uin=905090038&redirect=1&fromtag=111093","trackType":""} Dec 27 16:54:20 volumio volumio[701]: verbose: CURRENT POSITION 0 Dec 27 16:54:20 volumio volumio[701]: info: CoreStateMachine::syncState stateService stop Dec 27 16:54:20 volumio volumio[701]: info: CoreStateMachine::syncState currentStatus stop Dec 27 16:54:20 volumio volumio[701]: info: CoreStateMachine::pushState Dec 27 16:54:20 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:20 volumio volumio[701]: info: No code Dec 27 16:54:20 volumio volumio[701]: info: CoreStateMachine::pushState Dec 27 16:54:20 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:20 volumio volumio[701]: info: ------------------------------ 65ms Dec 27 16:54:20 volumio volumio[701]: info: Clearing queue after UPNP request Dec 27 16:54:20 volumio volumio[701]: info: CoreStateMachine::ClearQueue Dec 27 16:54:20 volumio volumio[701]: info: CoreStateMachine::stop Dec 27 16:54:20 volumio volumio[701]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 16:54:20 volumio volumio[701]: info: CorePlayQueue::clearPlayQueue Dec 27 16:54:20 volumio volumio[701]: info: CorePlayQueue::saveQueue Dec 27 16:54:20 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:20 volumio volumio[701]: info: CoreCommandRouter::volumioPushQueue Dec 27 16:54:20 volumio volumio[701]: info: Dec 27 16:54:20 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:20 volumio kernel: hwmon hwmon1: Undervoltage detected! Dec 27 16:54:20 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:20 volumio volumio[701]: error: updateQueue error: null Dec 27 16:54:20 volumio volumio[701]: info: ------------------------------ 3ms Dec 27 16:54:20 volumio volumio[701]: info: Starting UPNP Playback Dec 27 16:54:20 volumio volumio[701]: info: Preparing playback through UPNP Dec 27 16:54:20 volumio volumio[701]: info: CoreCommandRouter::volumioGetState Dec 27 16:54:20 volumio volumio[701]: info: CorePlayQueue::getTrack 0 Dec 27 16:54:20 volumio volumio[701]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 27 16:54:21 volumio volumio[701]: info: Dec 27 16:54:21 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:21 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:21 volumio volumio[701]: info: Dec 27 16:54:21 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:21 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:21 volumio volumio[701]: info: ------------------------------ 4ms Dec 27 16:54:21 volumio volumio[701]: info: ------------------------------ 3ms Dec 27 16:54:21 volumio volumio[701]: info: Dec 27 16:54:21 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:21 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:21 volumio volumio[701]: info: Dec 27 16:54:21 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:21 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:21 volumio volumio[701]: info: ------------------------------ 4ms Dec 27 16:54:21 volumio volumio[701]: info: ------------------------------ 3ms Dec 27 16:54:21 volumio volumio[701]: info: Dec 27 16:54:21 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:21 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:21 volumio volumio[701]: info: Dec 27 16:54:21 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:21 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:21 volumio volumio[701]: info: ------------------------------ 3ms Dec 27 16:54:21 volumio volumio[701]: info: ------------------------------ 3ms Dec 27 16:54:21 volumio volumio[701]: info: Dec 27 16:54:21 volumio volumio[701]: ---------------------------- MPD announces state update: player Dec 27 16:54:21 volumio volumio[701]: info: ControllerMpd::getState Dec 27 16:54:21 volumio volumio[701]: verbose: ControllerMpd::sendMpdCommand status Dec 27 16:54:21 volumio volumio[701]: info: Dec 27 16:54:21 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:21 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:21 volumio volumio[701]: info: Dec 27 16:54:21 volumio volumio[701]: ---------------------------- MPD announces state update: player Dec 27 16:54:21 volumio volumio[701]: info: sendMpdCommand status took 47 milliseconds Dec 27 16:54:21 volumio volumio[701]: info: ControllerMpd::getState Dec 27 16:54:21 volumio volumio[701]: verbose: ControllerMpd::sendMpdCommand status Dec 27 16:54:21 volumio volumio[701]: verbose: ControllerMpd::parseState Dec 27 16:54:21 volumio volumio[701]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 16:54:21 volumio volumio[701]: info: ------------------------------ 15ms Dec 27 16:54:21 volumio volumio[701]: info: sendMpdCommand status took 13 milliseconds Dec 27 16:54:21 volumio volumio[701]: info: sendMpdCommand playlistinfo took 12 milliseconds Dec 27 16:54:21 volumio volumio[701]: verbose: ControllerMpd::parseState Dec 27 16:54:21 volumio volumio[701]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 16:54:21 volumio volumio[701]: verbose: ControllerMpd::parseTrackInfo Dec 27 16:54:21 volumio volumio[701]: info: ControllerMpd::pushState Dec 27 16:54:21 volumio volumio[701]: info: CoreCommandRouter::servicePushState Dec 27 16:54:21 volumio volumio[701]: verbose: In UPNP mode Dec 27 16:54:21 volumio volumio[701]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":208,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"虹之间","artist":"王赫野/姚晓棠","album":"天赐的声音第五季 第4期","uri":"http://aqqmusic.tc.qq.com/M800000HoSGd4VQah3.mp3?guid=000000005307aef90000019af43fe5cf&vkey=DEBEA6ADB299EEA277433BE35EB1A3338F5EA394C5DAA58ABC5515D22A1F2913F36DF0B8D7CBA5ED63A7F1F11B60BDA16FDB9ECB3F0C08CE__v2b9ab709&uin=905090038&src=M800001tiAUP0oZOVp.mp3&redirect=1&fromtag=111093","trackType":""} Dec 27 16:54:21 volumio volumio[701]: verbose: CURRENT POSITION 0 Dec 27 16:54:21 volumio volumio[701]: info: CoreStateMachine::syncState stateService play Dec 27 16:54:21 volumio volumio[701]: info: CoreStateMachine::syncState currentStatus stop Dec 27 16:54:21 volumio volumio[701]: info: ------------------------------ 65ms Dec 27 16:54:21 volumio volumio[701]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 27 16:54:21 volumio volumio[701]: verbose: ControllerMpd::parseTrackInfo Dec 27 16:54:21 volumio volumio[701]: info: ControllerMpd::pushState Dec 27 16:54:21 volumio volumio[701]: info: CoreCommandRouter::servicePushState Dec 27 16:54:21 volumio volumio[701]: verbose: In UPNP mode Dec 27 16:54:21 volumio volumio[701]: verbose: STATE SERVICE {"status":"play","position":0,"seek":824,"duration":208,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"虹之间","artist":"王赫野/姚晓棠","album":"天赐的声音第五季 第4期","uri":"http://aqqmusic.tc.qq.com/M800000HoSGd4VQah3.mp3?guid=000000005307aef90000019af43fe5cf&vkey=DEBEA6ADB299EEA277433BE35EB1A3338F5EA394C5DAA58ABC5515D22A1F2913F36DF0B8D7CBA5ED63A7F1F11B60BDA16FDB9ECB3F0C08CE__v2b9ab709&uin=905090038&src=M800001tiAUP0oZOVp.mp3&redirect=1&fromtag=111093","trackType":""} Dec 27 16:54:21 volumio volumio[701]: verbose: CURRENT POSITION 0 Dec 27 16:54:21 volumio volumio[701]: info: CoreStateMachine::syncState stateService play Dec 27 16:54:21 volumio volumio[701]: info: CoreStateMachine::syncState currentStatus play Dec 27 16:54:21 volumio volumio[701]: info: Received an update from plugin. extracting info from payload Dec 27 16:54:21 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 27 16:54:21 volumio volumio[701]: info: CoreStateMachine::pushState Dec 27 16:54:21 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 16:54:21 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:21 volumio volumio[701]: info: CoreStateMachine::pushState Dec 27 16:54:21 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:21 volumio volumio[701]: info: ------------------------------ 44ms Dec 27 16:54:21 volumio volumio[701]: info: CoreCommandRouter::volumioGetQueue Dec 27 16:54:21 volumio volumio[701]: info: CoreStateMachine::getQueue Dec 27 16:54:21 volumio volumio[701]: info: CorePlayQueue::getQueue Dec 27 16:54:21 volumio volumio[701]: info: CoreCommandRouter::volumioGetQueue Dec 27 16:54:21 volumio volumio[701]: info: CoreStateMachine::getQueue Dec 27 16:54:21 volumio volumio[701]: info: CorePlayQueue::getQueue Dec 27 16:54:21 volumio volumio[701]: info: Dec 27 16:54:21 volumio volumio[701]: ---------------------------- MPD announces state update: player Dec 27 16:54:21 volumio volumio[701]: info: ControllerMpd::getState Dec 27 16:54:21 volumio volumio[701]: verbose: ControllerMpd::sendMpdCommand status Dec 27 16:54:21 volumio volumio[701]: info: sendMpdCommand status took 2 milliseconds Dec 27 16:54:21 volumio volumio[701]: verbose: ControllerMpd::parseState Dec 27 16:54:21 volumio volumio[701]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 16:54:21 volumio volumio[701]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 27 16:54:21 volumio volumio[701]: verbose: ControllerMpd::parseTrackInfo Dec 27 16:54:21 volumio volumio[701]: info: ControllerMpd::pushState Dec 27 16:54:21 volumio volumio[701]: info: CoreCommandRouter::servicePushState Dec 27 16:54:21 volumio volumio[701]: info: CoreStateMachine::pushState Dec 27 16:54:21 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:21 volumio volumio[701]: info: CorePlayQueue::getTrack 0 Dec 27 16:54:21 volumio volumio[701]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"虹之间","artist":"王赫野/姚晓棠","album":"天赐的声音第五季 第4期","uri":"http://aqqmusic.tc.qq.com/M800000HoSGd4VQah3.mp3?guid=000000005307aef90000019af43fe5cf&vkey=DEBEA6ADB299EEA277433BE35EB1A3338F5EA394C5DAA58ABC5515D22A1F2913F36DF0B8D7CBA5ED63A7F1F11B60BDA16FDB9ECB3F0C08CE__v2b9ab709&uin=905090038&src=M800001tiAUP0oZOVp.mp3&redirect=1&fromtag=111093","trackType":""} Dec 27 16:54:21 volumio volumio[701]: verbose: CURRENT POSITION 0 Dec 27 16:54:21 volumio volumio[701]: info: CoreStateMachine::syncState stateService stop Dec 27 16:54:21 volumio volumio[701]: info: CoreStateMachine::syncState currentStatus play Dec 27 16:54:21 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:21 volumio volumio[701]: info: CoreStateMachine::stPlaybackTimer Dec 27 16:54:21 volumio volumio[701]: info: ------------------------------ 19ms Dec 27 16:54:21 volumio volumio[701]: info: CoreCommandRouter::volumioGetQueue Dec 27 16:54:21 volumio volumio[701]: info: CoreStateMachine::getQueue Dec 27 16:54:21 volumio volumio[701]: info: CorePlayQueue::getQueue Dec 27 16:54:21 volumio volumio[701]: info: Clearing queue after UPNP request Dec 27 16:54:21 volumio volumio[701]: info: CoreStateMachine::ClearQueue Dec 27 16:54:21 volumio volumio[701]: info: CoreStateMachine::stop Dec 27 16:54:21 volumio volumio[701]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 16:54:21 volumio volumio[701]: info: CorePlayQueue::clearPlayQueue Dec 27 16:54:21 volumio volumio[701]: info: CorePlayQueue::saveQueue Dec 27 16:54:21 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:21 volumio volumio[701]: info: CoreCommandRouter::volumioPushQueue Dec 27 16:54:21 volumio volumio[701]: info: Dec 27 16:54:21 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:21 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:21 volumio volumio[701]: error: updateQueue error: null Dec 27 16:54:21 volumio volumio[701]: info: ------------------------------ 3ms Dec 27 16:54:21 volumio volumio[701]: info: Starting UPNP Playback Dec 27 16:54:21 volumio volumio[701]: info: Preparing playback through UPNP Dec 27 16:54:21 volumio volumio[701]: info: CoreCommandRouter::volumioGetState Dec 27 16:54:21 volumio volumio[701]: info: CorePlayQueue::getTrack 0 Dec 27 16:54:21 volumio volumio[701]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 27 16:54:22 volumio volumio[701]: info: Dec 27 16:54:22 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:22 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:22 volumio volumio[701]: info: Dec 27 16:54:22 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:22 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:22 volumio volumio[701]: info: ------------------------------ 2ms Dec 27 16:54:22 volumio volumio[701]: info: ------------------------------ 1ms Dec 27 16:54:22 volumio volumio[701]: info: Dec 27 16:54:22 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:22 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:22 volumio volumio[701]: info: Dec 27 16:54:22 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:22 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:22 volumio volumio[701]: info: ------------------------------ 2ms Dec 27 16:54:22 volumio volumio[701]: info: ------------------------------ 1ms Dec 27 16:54:22 volumio volumio[701]: info: Dec 27 16:54:22 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:22 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:22 volumio volumio[701]: info: ------------------------------ 1ms Dec 27 16:54:22 volumio volumio[701]: info: Dec 27 16:54:22 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:22 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:22 volumio volumio[701]: info: ------------------------------ 1ms Dec 27 16:54:22 volumio volumio[701]: info: Dec 27 16:54:22 volumio volumio[701]: ---------------------------- MPD announces state update: player Dec 27 16:54:22 volumio volumio[701]: info: ControllerMpd::getState Dec 27 16:54:22 volumio volumio[701]: verbose: ControllerMpd::sendMpdCommand status Dec 27 16:54:22 volumio volumio[701]: info: Dec 27 16:54:22 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:22 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:22 volumio volumio[701]: info: Dec 27 16:54:22 volumio volumio[701]: ---------------------------- MPD announces state update: player Dec 27 16:54:22 volumio volumio[701]: info: sendMpdCommand status took 35 milliseconds Dec 27 16:54:22 volumio volumio[701]: info: ControllerMpd::getState Dec 27 16:54:22 volumio volumio[701]: verbose: ControllerMpd::sendMpdCommand status Dec 27 16:54:22 volumio volumio[701]: verbose: ControllerMpd::parseState Dec 27 16:54:22 volumio volumio[701]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 16:54:22 volumio volumio[701]: info: ------------------------------ 17ms Dec 27 16:54:22 volumio volumio[701]: info: sendMpdCommand status took 6 milliseconds Dec 27 16:54:22 volumio volumio[701]: info: sendMpdCommand playlistinfo took 6 milliseconds Dec 27 16:54:22 volumio volumio[701]: verbose: ControllerMpd::parseState Dec 27 16:54:22 volumio volumio[701]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 16:54:22 volumio volumio[701]: verbose: ControllerMpd::parseTrackInfo Dec 27 16:54:22 volumio volumio[701]: info: ControllerMpd::pushState Dec 27 16:54:22 volumio volumio[701]: info: CoreCommandRouter::servicePushState Dec 27 16:54:22 volumio volumio[701]: verbose: In UPNP mode Dec 27 16:54:22 volumio volumio[701]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":273,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"裹着心的光","artist":"林俊杰","album":"裹着心的光","uri":"http://aqqmusic.tc.qq.com/M800000IZZ524R6Isl.mp3?guid=000000005307aef90000019af43fe5cf&vkey=335C5114A17E0EB0475D5BB443A91C573001198D2BB7CD14C9DFAEFF02D9001F6C6FC930371D751120DDB248E1488F8ACA8CDB9CACE4090C__v2b9ab707&uin=905090038&redirect=1&fromtag=111093","trackType":""} Dec 27 16:54:22 volumio volumio[701]: verbose: CURRENT POSITION 0 Dec 27 16:54:22 volumio volumio[701]: info: CoreStateMachine::syncState stateService play Dec 27 16:54:22 volumio volumio[701]: info: CoreStateMachine::syncState currentStatus stop Dec 27 16:54:22 volumio volumio[701]: info: ------------------------------ 46ms Dec 27 16:54:22 volumio volumio[701]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 27 16:54:22 volumio volumio[701]: verbose: ControllerMpd::parseTrackInfo Dec 27 16:54:22 volumio volumio[701]: info: ControllerMpd::pushState Dec 27 16:54:22 volumio volumio[701]: info: CoreCommandRouter::servicePushState Dec 27 16:54:22 volumio volumio[701]: verbose: In UPNP mode Dec 27 16:54:22 volumio volumio[701]: verbose: STATE SERVICE {"status":"play","position":0,"seek":881,"duration":273,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"裹着心的光","artist":"林俊杰","album":"裹着心的光","uri":"http://aqqmusic.tc.qq.com/M800000IZZ524R6Isl.mp3?guid=000000005307aef90000019af43fe5cf&vkey=335C5114A17E0EB0475D5BB443A91C573001198D2BB7CD14C9DFAEFF02D9001F6C6FC930371D751120DDB248E1488F8ACA8CDB9CACE4090C__v2b9ab707&uin=905090038&redirect=1&fromtag=111093","trackType":""} Dec 27 16:54:22 volumio volumio[701]: verbose: CURRENT POSITION 0 Dec 27 16:54:22 volumio volumio[701]: info: CoreStateMachine::syncState stateService play Dec 27 16:54:22 volumio volumio[701]: info: CoreStateMachine::syncState currentStatus play Dec 27 16:54:22 volumio volumio[701]: info: Received an update from plugin. extracting info from payload Dec 27 16:54:22 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 27 16:54:22 volumio volumio[701]: info: CoreStateMachine::pushState Dec 27 16:54:22 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 16:54:22 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:22 volumio volumio[701]: info: CoreStateMachine::pushState Dec 27 16:54:22 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:22 volumio volumio[701]: info: ------------------------------ 39ms Dec 27 16:54:22 volumio volumio[701]: info: CoreCommandRouter::volumioGetQueue Dec 27 16:54:22 volumio volumio[701]: info: CoreStateMachine::getQueue Dec 27 16:54:22 volumio volumio[701]: info: CorePlayQueue::getQueue Dec 27 16:54:22 volumio volumio[701]: info: CoreCommandRouter::volumioGetQueue Dec 27 16:54:22 volumio volumio[701]: info: CoreStateMachine::getQueue Dec 27 16:54:22 volumio volumio[701]: info: CorePlayQueue::getQueue Dec 27 16:54:22 volumio volumio[701]: info: Dec 27 16:54:22 volumio volumio[701]: ---------------------------- MPD announces state update: player Dec 27 16:54:22 volumio volumio[701]: info: ControllerMpd::getState Dec 27 16:54:22 volumio volumio[701]: verbose: ControllerMpd::sendMpdCommand status Dec 27 16:54:22 volumio volumio[701]: info: sendMpdCommand status took 1 milliseconds Dec 27 16:54:22 volumio volumio[701]: verbose: ControllerMpd::parseState Dec 27 16:54:22 volumio volumio[701]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 16:54:22 volumio volumio[701]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 27 16:54:22 volumio volumio[701]: verbose: ControllerMpd::parseTrackInfo Dec 27 16:54:22 volumio volumio[701]: info: ControllerMpd::pushState Dec 27 16:54:22 volumio volumio[701]: info: CoreCommandRouter::servicePushState Dec 27 16:54:22 volumio volumio[701]: info: CoreStateMachine::pushState Dec 27 16:54:22 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:22 volumio volumio[701]: info: CorePlayQueue::getTrack 0 Dec 27 16:54:22 volumio volumio[701]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"裹着心的光","artist":"林俊杰","album":"裹着心的光","uri":"http://aqqmusic.tc.qq.com/M800000IZZ524R6Isl.mp3?guid=000000005307aef90000019af43fe5cf&vkey=335C5114A17E0EB0475D5BB443A91C573001198D2BB7CD14C9DFAEFF02D9001F6C6FC930371D751120DDB248E1488F8ACA8CDB9CACE4090C__v2b9ab707&uin=905090038&redirect=1&fromtag=111093","trackType":""} Dec 27 16:54:22 volumio volumio[701]: verbose: CURRENT POSITION 0 Dec 27 16:54:22 volumio volumio[701]: info: CoreStateMachine::syncState stateService stop Dec 27 16:54:22 volumio volumio[701]: info: CoreStateMachine::syncState currentStatus play Dec 27 16:54:22 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:22 volumio volumio[701]: info: CoreStateMachine::stPlaybackTimer Dec 27 16:54:22 volumio volumio[701]: info: ------------------------------ 13ms Dec 27 16:54:22 volumio volumio[701]: info: CoreCommandRouter::volumioGetQueue Dec 27 16:54:22 volumio volumio[701]: info: CoreStateMachine::getQueue Dec 27 16:54:22 volumio volumio[701]: info: CorePlayQueue::getQueue Dec 27 16:54:22 volumio volumio[701]: info: Clearing queue after UPNP request Dec 27 16:54:22 volumio volumio[701]: info: CoreStateMachine::ClearQueue Dec 27 16:54:22 volumio volumio[701]: info: CoreStateMachine::stop Dec 27 16:54:22 volumio volumio[701]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 16:54:22 volumio volumio[701]: info: CorePlayQueue::clearPlayQueue Dec 27 16:54:22 volumio volumio[701]: info: CorePlayQueue::saveQueue Dec 27 16:54:22 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:22 volumio volumio[701]: info: CoreCommandRouter::volumioPushQueue Dec 27 16:54:22 volumio volumio[701]: info: Dec 27 16:54:22 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:22 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:22 volumio volumio[701]: error: updateQueue error: null Dec 27 16:54:23 volumio volumio[701]: info: ------------------------------ 5ms Dec 27 16:54:23 volumio volumio[701]: info: Starting UPNP Playback Dec 27 16:54:23 volumio volumio[701]: info: Preparing playback through UPNP Dec 27 16:54:23 volumio volumio[701]: info: CoreCommandRouter::volumioGetState Dec 27 16:54:23 volumio volumio[701]: info: CorePlayQueue::getTrack 0 Dec 27 16:54:23 volumio volumio[701]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 27 16:54:23 volumio volumio[701]: info: Dec 27 16:54:23 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:23 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:23 volumio volumio[701]: info: Dec 27 16:54:23 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:23 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:23 volumio volumio[701]: info: ------------------------------ 7ms Dec 27 16:54:23 volumio volumio[701]: info: ------------------------------ 5ms Dec 27 16:54:23 volumio volumio[701]: info: Dec 27 16:54:23 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:23 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:23 volumio volumio[701]: info: ------------------------------ 3ms Dec 27 16:54:23 volumio volumio[701]: info: Dec 27 16:54:23 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:23 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:23 volumio volumio[701]: info: Dec 27 16:54:23 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:23 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:23 volumio volumio[701]: info: ------------------------------ 6ms Dec 27 16:54:23 volumio volumio[701]: info: ------------------------------ 6ms Dec 27 16:54:23 volumio volumio[701]: info: Dec 27 16:54:23 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:23 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:23 volumio volumio[701]: info: ------------------------------ 4ms Dec 27 16:54:23 volumio volumio[701]: info: Dec 27 16:54:23 volumio volumio[701]: ---------------------------- MPD announces state update: player Dec 27 16:54:23 volumio volumio[701]: info: ControllerMpd::getState Dec 27 16:54:23 volumio volumio[701]: verbose: ControllerMpd::sendMpdCommand status Dec 27 16:54:23 volumio volumio[701]: info: sendMpdCommand status took 48 milliseconds Dec 27 16:54:23 volumio volumio[701]: verbose: ControllerMpd::parseState Dec 27 16:54:23 volumio volumio[701]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 16:54:23 volumio volumio[701]: info: Dec 27 16:54:23 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:23 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:23 volumio volumio[701]: info: Dec 27 16:54:23 volumio volumio[701]: ---------------------------- MPD announces state update: player Dec 27 16:54:23 volumio volumio[701]: info: ControllerMpd::getState Dec 27 16:54:23 volumio volumio[701]: verbose: ControllerMpd::sendMpdCommand status Dec 27 16:54:23 volumio volumio[701]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 27 16:54:23 volumio volumio[701]: verbose: ControllerMpd::parseTrackInfo Dec 27 16:54:23 volumio volumio[701]: info: ControllerMpd::pushState Dec 27 16:54:23 volumio volumio[701]: info: CoreCommandRouter::servicePushState Dec 27 16:54:23 volumio volumio[701]: verbose: In UPNP mode Dec 27 16:54:23 volumio volumio[701]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":266,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Lemon Tree","artist":"YELLOW黄宣","album":"歌手2024 第7期","uri":"http://aqqmusic.tc.qq.com/M8000031iD7p2ALRAt.mp3?guid=000000005307aef90000019af43fe5cf&vkey=4B7D0EE70ADB999FE2BBFE80B45611DEEFCF4B71AF4EE936D7F730E362727B0B78E68F6EDDEED255C671D83B0A819AB5996968CDF8445392__v2b9ab707&uin=905090038&src=M800003249Np0K8MBf.mp3&redirect=1&fromtag=111093","trackType":""} Dec 27 16:54:23 volumio volumio[701]: verbose: CURRENT POSITION 0 Dec 27 16:54:23 volumio volumio[701]: info: CoreStateMachine::syncState stateService play Dec 27 16:54:23 volumio volumio[701]: info: CoreStateMachine::syncState currentStatus stop Dec 27 16:54:23 volumio volumio[701]: info: ------------------------------ 62ms Dec 27 16:54:23 volumio volumio[701]: info: ------------------------------ 12ms Dec 27 16:54:23 volumio volumio[701]: info: sendMpdCommand status took 11 milliseconds Dec 27 16:54:23 volumio volumio[701]: verbose: ControllerMpd::parseState Dec 27 16:54:23 volumio volumio[701]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 16:54:23 volumio volumio[701]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 27 16:54:23 volumio volumio[701]: verbose: ControllerMpd::parseTrackInfo Dec 27 16:54:23 volumio volumio[701]: info: ControllerMpd::pushState Dec 27 16:54:23 volumio volumio[701]: info: CoreCommandRouter::servicePushState Dec 27 16:54:23 volumio volumio[701]: verbose: In UPNP mode Dec 27 16:54:23 volumio volumio[701]: verbose: STATE SERVICE {"status":"play","position":0,"seek":881,"duration":266,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Lemon Tree","artist":"YELLOW黄宣","album":"歌手2024 第7期","uri":"http://aqqmusic.tc.qq.com/M8000031iD7p2ALRAt.mp3?guid=000000005307aef90000019af43fe5cf&vkey=4B7D0EE70ADB999FE2BBFE80B45611DEEFCF4B71AF4EE936D7F730E362727B0B78E68F6EDDEED255C671D83B0A819AB5996968CDF8445392__v2b9ab707&uin=905090038&src=M800003249Np0K8MBf.mp3&redirect=1&fromtag=111093","trackType":""} Dec 27 16:54:23 volumio volumio[701]: verbose: CURRENT POSITION 0 Dec 27 16:54:23 volumio volumio[701]: info: CoreStateMachine::syncState stateService play Dec 27 16:54:23 volumio volumio[701]: info: CoreStateMachine::syncState currentStatus play Dec 27 16:54:23 volumio volumio[701]: info: Received an update from plugin. extracting info from payload Dec 27 16:54:23 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 27 16:54:23 volumio volumio[701]: info: CoreStateMachine::pushState Dec 27 16:54:23 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 16:54:23 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:23 volumio volumio[701]: info: CoreStateMachine::pushState Dec 27 16:54:23 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:23 volumio volumio[701]: info: ------------------------------ 42ms Dec 27 16:54:23 volumio volumio[701]: info: CoreCommandRouter::volumioGetQueue Dec 27 16:54:23 volumio volumio[701]: info: CoreStateMachine::getQueue Dec 27 16:54:23 volumio volumio[701]: info: CorePlayQueue::getQueue Dec 27 16:54:23 volumio volumio[701]: info: CoreCommandRouter::volumioGetQueue Dec 27 16:54:23 volumio volumio[701]: info: CoreStateMachine::getQueue Dec 27 16:54:23 volumio volumio[701]: info: CorePlayQueue::getQueue Dec 27 16:54:24 volumio volumio[701]: info: Dec 27 16:54:24 volumio volumio[701]: ---------------------------- MPD announces state update: player Dec 27 16:54:24 volumio volumio[701]: info: ControllerMpd::getState Dec 27 16:54:24 volumio volumio[701]: verbose: ControllerMpd::sendMpdCommand status Dec 27 16:54:24 volumio volumio[701]: info: sendMpdCommand status took 1 milliseconds Dec 27 16:54:24 volumio volumio[701]: verbose: ControllerMpd::parseState Dec 27 16:54:24 volumio volumio[701]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 16:54:24 volumio volumio[701]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 27 16:54:24 volumio volumio[701]: verbose: ControllerMpd::parseTrackInfo Dec 27 16:54:24 volumio volumio[701]: info: ControllerMpd::pushState Dec 27 16:54:24 volumio volumio[701]: info: CoreCommandRouter::servicePushState Dec 27 16:54:24 volumio volumio[701]: info: CoreStateMachine::pushState Dec 27 16:54:24 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:24 volumio volumio[701]: info: CorePlayQueue::getTrack 0 Dec 27 16:54:24 volumio volumio[701]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Lemon Tree","artist":"YELLOW黄宣","album":"歌手2024 第7期","uri":"http://aqqmusic.tc.qq.com/M8000031iD7p2ALRAt.mp3?guid=000000005307aef90000019af43fe5cf&vkey=4B7D0EE70ADB999FE2BBFE80B45611DEEFCF4B71AF4EE936D7F730E362727B0B78E68F6EDDEED255C671D83B0A819AB5996968CDF8445392__v2b9ab707&uin=905090038&src=M800003249Np0K8MBf.mp3&redirect=1&fromtag=111093","trackType":""} Dec 27 16:54:24 volumio volumio[701]: verbose: CURRENT POSITION 0 Dec 27 16:54:24 volumio volumio[701]: info: CoreStateMachine::syncState stateService stop Dec 27 16:54:24 volumio volumio[701]: info: CoreStateMachine::syncState currentStatus play Dec 27 16:54:24 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:24 volumio volumio[701]: info: CoreStateMachine::stPlaybackTimer Dec 27 16:54:24 volumio volumio[701]: info: ------------------------------ 22ms Dec 27 16:54:24 volumio volumio[701]: info: CoreCommandRouter::volumioGetQueue Dec 27 16:54:24 volumio volumio[701]: info: CoreStateMachine::getQueue Dec 27 16:54:24 volumio volumio[701]: info: CorePlayQueue::getQueue Dec 27 16:54:25 volumio volumio[701]: info: Clearing queue after UPNP request Dec 27 16:54:25 volumio volumio[701]: info: CoreStateMachine::ClearQueue Dec 27 16:54:25 volumio volumio[701]: info: CoreStateMachine::stop Dec 27 16:54:25 volumio volumio[701]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 16:54:25 volumio volumio[701]: info: CorePlayQueue::clearPlayQueue Dec 27 16:54:25 volumio volumio[701]: info: CorePlayQueue::saveQueue Dec 27 16:54:25 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:25 volumio volumio[701]: info: CoreCommandRouter::volumioPushQueue Dec 27 16:54:25 volumio volumio[701]: info: Dec 27 16:54:25 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:25 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:25 volumio volumio[701]: error: updateQueue error: null Dec 27 16:54:25 volumio volumio[701]: info: ------------------------------ 2ms Dec 27 16:54:25 volumio volumio[701]: info: Starting UPNP Playback Dec 27 16:54:25 volumio volumio[701]: info: Preparing playback through UPNP Dec 27 16:54:25 volumio volumio[701]: info: CoreCommandRouter::volumioGetState Dec 27 16:54:25 volumio volumio[701]: info: CorePlayQueue::getTrack 0 Dec 27 16:54:25 volumio volumio[701]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 27 16:54:25 volumio volumio[701]: info: Dec 27 16:54:25 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:25 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:25 volumio volumio[701]: info: ------------------------------ 2ms Dec 27 16:54:25 volumio volumio[701]: info: Dec 27 16:54:25 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:25 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:25 volumio volumio[701]: info: ------------------------------ 1ms Dec 27 16:54:25 volumio volumio[701]: info: Dec 27 16:54:25 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:25 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:25 volumio volumio[701]: info: ------------------------------ 1ms Dec 27 16:54:25 volumio volumio[701]: info: Dec 27 16:54:25 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:25 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:25 volumio volumio[701]: info: ------------------------------ 1ms Dec 27 16:54:25 volumio volumio[701]: info: Dec 27 16:54:25 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:25 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:25 volumio volumio[701]: info: ------------------------------ 1ms Dec 27 16:54:25 volumio volumio[701]: info: Dec 27 16:54:25 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:25 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:25 volumio volumio[701]: info: ------------------------------ 1ms Dec 27 16:54:26 volumio volumio[701]: info: Dec 27 16:54:26 volumio volumio[701]: ---------------------------- MPD announces state update: player Dec 27 16:54:26 volumio volumio[701]: info: ControllerMpd::getState Dec 27 16:54:26 volumio volumio[701]: verbose: ControllerMpd::sendMpdCommand status Dec 27 16:54:26 volumio volumio[701]: info: Dec 27 16:54:26 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:26 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:26 volumio volumio[701]: info: Dec 27 16:54:26 volumio volumio[701]: ---------------------------- MPD announces state update: player Dec 27 16:54:26 volumio volumio[701]: info: sendMpdCommand status took 26 milliseconds Dec 27 16:54:26 volumio volumio[701]: info: ControllerMpd::getState Dec 27 16:54:26 volumio volumio[701]: verbose: ControllerMpd::sendMpdCommand status Dec 27 16:54:26 volumio volumio[701]: verbose: ControllerMpd::parseState Dec 27 16:54:26 volumio volumio[701]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 16:54:26 volumio volumio[701]: info: ------------------------------ 9ms Dec 27 16:54:26 volumio volumio[701]: info: sendMpdCommand status took 2 milliseconds Dec 27 16:54:26 volumio volumio[701]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 27 16:54:26 volumio volumio[701]: verbose: ControllerMpd::parseState Dec 27 16:54:26 volumio volumio[701]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 16:54:26 volumio volumio[701]: verbose: ControllerMpd::parseTrackInfo Dec 27 16:54:26 volumio volumio[701]: info: ControllerMpd::pushState Dec 27 16:54:26 volumio volumio[701]: info: CoreCommandRouter::servicePushState Dec 27 16:54:26 volumio volumio[701]: verbose: In UPNP mode Dec 27 16:54:26 volumio volumio[701]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":315,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"东风破","artist":"周杰伦","album":"叶惠美","uri":"http://aqqmusic.tc.qq.com/M800002DidUP2OXR90.mp3?guid=000000005307aef90000019af43fe5cf&vkey=5BDD7458938FEBC12245E3CE543A4771D6BB3C3FB92C18C9ABBBEEF50D21E08A09FF4B135D9F830E36D9DA2FD26BAB9FC76A17A1E30C1DCC__v2b9ab707&uin=905090038&redirect=1&fromtag=111093","trackType":""} Dec 27 16:54:26 volumio volumio[701]: verbose: CURRENT POSITION 0 Dec 27 16:54:26 volumio volumio[701]: info: CoreStateMachine::syncState stateService play Dec 27 16:54:26 volumio volumio[701]: info: CoreStateMachine::syncState currentStatus stop Dec 27 16:54:26 volumio volumio[701]: info: ------------------------------ 32ms Dec 27 16:54:26 volumio volumio[701]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 27 16:54:26 volumio volumio[701]: verbose: ControllerMpd::parseTrackInfo Dec 27 16:54:26 volumio volumio[701]: info: ControllerMpd::pushState Dec 27 16:54:26 volumio volumio[701]: info: CoreCommandRouter::servicePushState Dec 27 16:54:26 volumio volumio[701]: verbose: In UPNP mode Dec 27 16:54:26 volumio volumio[701]: verbose: STATE SERVICE {"status":"play","position":0,"seek":881,"duration":315,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"东风破","artist":"周杰伦","album":"叶惠美","uri":"http://aqqmusic.tc.qq.com/M800002DidUP2OXR90.mp3?guid=000000005307aef90000019af43fe5cf&vkey=5BDD7458938FEBC12245E3CE543A4771D6BB3C3FB92C18C9ABBBEEF50D21E08A09FF4B135D9F830E36D9DA2FD26BAB9FC76A17A1E30C1DCC__v2b9ab707&uin=905090038&redirect=1&fromtag=111093","trackType":""} Dec 27 16:54:26 volumio volumio[701]: verbose: CURRENT POSITION 0 Dec 27 16:54:26 volumio volumio[701]: info: CoreStateMachine::syncState stateService play Dec 27 16:54:26 volumio volumio[701]: info: CoreStateMachine::syncState currentStatus play Dec 27 16:54:26 volumio volumio[701]: info: Received an update from plugin. extracting info from payload Dec 27 16:54:26 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 27 16:54:26 volumio volumio[701]: info: CoreStateMachine::pushState Dec 27 16:54:26 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 16:54:26 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:26 volumio volumio[701]: info: CoreStateMachine::pushState Dec 27 16:54:26 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:26 volumio volumio[701]: info: ------------------------------ 18ms Dec 27 16:54:26 volumio volumio[701]: info: CoreCommandRouter::volumioGetQueue Dec 27 16:54:26 volumio volumio[701]: info: CoreStateMachine::getQueue Dec 27 16:54:26 volumio volumio[701]: info: CorePlayQueue::getQueue Dec 27 16:54:26 volumio volumio[701]: info: CoreCommandRouter::volumioGetQueue Dec 27 16:54:26 volumio volumio[701]: info: CoreStateMachine::getQueue Dec 27 16:54:26 volumio volumio[701]: info: CorePlayQueue::getQueue Dec 27 16:54:26 volumio volumio[701]: info: Dec 27 16:54:26 volumio volumio[701]: ---------------------------- MPD announces state update: player Dec 27 16:54:26 volumio volumio[701]: info: ControllerMpd::getState Dec 27 16:54:26 volumio volumio[701]: verbose: ControllerMpd::sendMpdCommand status Dec 27 16:54:26 volumio volumio[701]: info: sendMpdCommand status took 2 milliseconds Dec 27 16:54:26 volumio volumio[701]: verbose: ControllerMpd::parseState Dec 27 16:54:26 volumio volumio[701]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 16:54:26 volumio volumio[701]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 27 16:54:26 volumio volumio[701]: verbose: ControllerMpd::parseTrackInfo Dec 27 16:54:26 volumio volumio[701]: info: ControllerMpd::pushState Dec 27 16:54:26 volumio volumio[701]: info: CoreCommandRouter::servicePushState Dec 27 16:54:26 volumio volumio[701]: info: CoreStateMachine::pushState Dec 27 16:54:26 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:26 volumio volumio[701]: info: CorePlayQueue::getTrack 0 Dec 27 16:54:26 volumio volumio[701]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"东风破","artist":"周杰伦","album":"叶惠美","uri":"http://aqqmusic.tc.qq.com/M800002DidUP2OXR90.mp3?guid=000000005307aef90000019af43fe5cf&vkey=5BDD7458938FEBC12245E3CE543A4771D6BB3C3FB92C18C9ABBBEEF50D21E08A09FF4B135D9F830E36D9DA2FD26BAB9FC76A17A1E30C1DCC__v2b9ab707&uin=905090038&redirect=1&fromtag=111093","trackType":""} Dec 27 16:54:26 volumio volumio[701]: verbose: CURRENT POSITION 0 Dec 27 16:54:26 volumio volumio[701]: info: CoreStateMachine::syncState stateService stop Dec 27 16:54:26 volumio volumio[701]: info: CoreStateMachine::syncState currentStatus play Dec 27 16:54:26 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:26 volumio volumio[701]: info: CoreStateMachine::stPlaybackTimer Dec 27 16:54:26 volumio volumio[701]: info: ------------------------------ 18ms Dec 27 16:54:26 volumio volumio[701]: info: CoreCommandRouter::volumioGetQueue Dec 27 16:54:26 volumio volumio[701]: info: CoreStateMachine::getQueue Dec 27 16:54:26 volumio volumio[701]: info: CorePlayQueue::getQueue Dec 27 16:54:26 volumio volumio[701]: info: Clearing queue after UPNP request Dec 27 16:54:27 volumio volumio[701]: info: CoreStateMachine::ClearQueue Dec 27 16:54:27 volumio volumio[701]: info: CoreStateMachine::stop Dec 27 16:54:27 volumio volumio[701]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 16:54:27 volumio volumio[701]: info: CorePlayQueue::clearPlayQueue Dec 27 16:54:27 volumio volumio[701]: info: CorePlayQueue::saveQueue Dec 27 16:54:27 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:27 volumio volumio[701]: info: CoreCommandRouter::volumioPushQueue Dec 27 16:54:27 volumio volumio[701]: info: Dec 27 16:54:27 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:27 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:27 volumio volumio[701]: error: updateQueue error: null Dec 27 16:54:27 volumio volumio[701]: info: ------------------------------ 3ms Dec 27 16:54:27 volumio volumio[701]: info: Starting UPNP Playback Dec 27 16:54:27 volumio volumio[701]: info: Preparing playback through UPNP Dec 27 16:54:27 volumio volumio[701]: info: CoreCommandRouter::volumioGetState Dec 27 16:54:27 volumio volumio[701]: info: CorePlayQueue::getTrack 0 Dec 27 16:54:27 volumio volumio[701]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 27 16:54:27 volumio volumio[701]: info: Dec 27 16:54:27 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:27 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:27 volumio volumio[701]: info: ------------------------------ 2ms Dec 27 16:54:27 volumio volumio[701]: info: Dec 27 16:54:27 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:27 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:27 volumio volumio[701]: info: ------------------------------ 3ms Dec 27 16:54:27 volumio volumio[701]: info: Dec 27 16:54:27 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:27 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:27 volumio volumio[701]: info: ------------------------------ 3ms Dec 27 16:54:27 volumio volumio[701]: info: Dec 27 16:54:27 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:27 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:27 volumio volumio[701]: info: ------------------------------ 3ms Dec 27 16:54:27 volumio volumio[701]: info: Dec 27 16:54:27 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:27 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:27 volumio volumio[701]: info: ------------------------------ 3ms Dec 27 16:54:27 volumio volumio[701]: info: Dec 27 16:54:27 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:27 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:27 volumio volumio[701]: info: ------------------------------ 3ms Dec 27 16:54:27 volumio volumio[701]: info: Dec 27 16:54:27 volumio volumio[701]: ---------------------------- MPD announces state update: player Dec 27 16:54:27 volumio volumio[701]: info: ControllerMpd::getState Dec 27 16:54:27 volumio volumio[701]: verbose: ControllerMpd::sendMpdCommand status Dec 27 16:54:27 volumio volumio[701]: info: sendMpdCommand status took 45 milliseconds Dec 27 16:54:27 volumio volumio[701]: verbose: ControllerMpd::parseState Dec 27 16:54:27 volumio volumio[701]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 16:54:27 volumio volumio[701]: info: Dec 27 16:54:27 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:27 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:27 volumio volumio[701]: info: Dec 27 16:54:27 volumio volumio[701]: ---------------------------- MPD announces state update: player Dec 27 16:54:27 volumio volumio[701]: info: Dec 27 16:54:27 volumio volumio[701]: ---------------------------- MPD announces state update: player Dec 27 16:54:27 volumio volumio[701]: info: ControllerMpd::getState Dec 27 16:54:27 volumio volumio[701]: verbose: ControllerMpd::sendMpdCommand status Dec 27 16:54:27 volumio volumio[701]: info: sendMpdCommand playlistinfo took 8 milliseconds Dec 27 16:54:27 volumio volumio[701]: info: ControllerMpd::getState Dec 27 16:54:27 volumio volumio[701]: verbose: ControllerMpd::sendMpdCommand status Dec 27 16:54:27 volumio volumio[701]: verbose: ControllerMpd::parseTrackInfo Dec 27 16:54:27 volumio volumio[701]: info: ControllerMpd::pushState Dec 27 16:54:27 volumio volumio[701]: info: CoreCommandRouter::servicePushState Dec 27 16:54:27 volumio volumio[701]: verbose: In UPNP mode Dec 27 16:54:27 volumio volumio[701]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":242,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"曹操","artist":"林俊杰","album":"曹操","uri":"http://aqqmusic.tc.qq.com/M800004PaYp94CiV3Z.mp3?guid=000000005307aef90000019af43fe5cf&vkey=F8B0AA2D59F357A63327B0E5CA9BEDA71CFB843D64E5B5C2930C7F4043FC523B1D5C372A07C592729A2C2929ED2CC8AEF726CB8BF54CDC1C__v2b9aae6a&uin=905090038&redirect=1&fromtag=111093","trackType":""} Dec 27 16:54:27 volumio volumio[701]: verbose: CURRENT POSITION 0 Dec 27 16:54:27 volumio volumio[701]: info: CoreStateMachine::syncState stateService play Dec 27 16:54:27 volumio volumio[701]: info: CoreStateMachine::syncState currentStatus stop Dec 27 16:54:27 volumio volumio[701]: info: ------------------------------ 62ms Dec 27 16:54:27 volumio volumio[701]: info: ------------------------------ 9ms Dec 27 16:54:27 volumio volumio[701]: info: sendMpdCommand status took 8 milliseconds Dec 27 16:54:27 volumio volumio[701]: info: sendMpdCommand status took 7 milliseconds Dec 27 16:54:27 volumio volumio[701]: verbose: ControllerMpd::parseState Dec 27 16:54:27 volumio volumio[701]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 16:54:27 volumio volumio[701]: verbose: ControllerMpd::parseState Dec 27 16:54:27 volumio volumio[701]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 16:54:27 volumio volumio[701]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 27 16:54:27 volumio volumio[701]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 27 16:54:27 volumio volumio[701]: verbose: ControllerMpd::parseTrackInfo Dec 27 16:54:27 volumio volumio[701]: verbose: ControllerMpd::parseTrackInfo Dec 27 16:54:27 volumio volumio[701]: info: ControllerMpd::pushState Dec 27 16:54:27 volumio volumio[701]: info: CoreCommandRouter::servicePushState Dec 27 16:54:27 volumio volumio[701]: info: CoreStateMachine::pushState Dec 27 16:54:27 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 16:54:27 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:27 volumio volumio[701]: info: CorePlayQueue::getTrack 0 Dec 27 16:54:27 volumio volumio[701]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"曹操","artist":"林俊杰","album":"曹操","uri":"http://aqqmusic.tc.qq.com/M800004PaYp94CiV3Z.mp3?guid=000000005307aef90000019af43fe5cf&vkey=F8B0AA2D59F357A63327B0E5CA9BEDA71CFB843D64E5B5C2930C7F4043FC523B1D5C372A07C592729A2C2929ED2CC8AEF726CB8BF54CDC1C__v2b9aae6a&uin=905090038&redirect=1&fromtag=111093","trackType":""} Dec 27 16:54:27 volumio volumio[701]: verbose: CURRENT POSITION 0 Dec 27 16:54:27 volumio volumio[701]: info: CoreStateMachine::syncState stateService stop Dec 27 16:54:27 volumio volumio[701]: info: CoreStateMachine::syncState currentStatus play Dec 27 16:54:27 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:27 volumio volumio[701]: info: CoreStateMachine::stPlaybackTimer Dec 27 16:54:27 volumio volumio[701]: info: ControllerMpd::pushState Dec 27 16:54:27 volumio volumio[701]: info: CoreCommandRouter::servicePushState Dec 27 16:54:27 volumio volumio[701]: info: CoreStateMachine::pushState Dec 27 16:54:27 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 16:54:27 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:27 volumio volumio[701]: info: CorePlayQueue::getTrack 0 Dec 27 16:54:27 volumio volumio[701]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"曹操","artist":"林俊杰","album":"曹操","uri":"http://aqqmusic.tc.qq.com/M800004PaYp94CiV3Z.mp3?guid=000000005307aef90000019af43fe5cf&vkey=F8B0AA2D59F357A63327B0E5CA9BEDA71CFB843D64E5B5C2930C7F4043FC523B1D5C372A07C592729A2C2929ED2CC8AEF726CB8BF54CDC1C__v2b9aae6a&uin=905090038&redirect=1&fromtag=111093","trackType":""} Dec 27 16:54:27 volumio volumio[701]: verbose: CURRENT POSITION 0 Dec 27 16:54:27 volumio volumio[701]: info: CoreStateMachine::syncState stateService stop Dec 27 16:54:27 volumio volumio[701]: info: CoreStateMachine::syncState currentStatus stop Dec 27 16:54:27 volumio volumio[701]: info: CoreStateMachine::pushState Dec 27 16:54:27 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:27 volumio volumio[701]: info: No code Dec 27 16:54:27 volumio volumio[701]: info: CoreStateMachine::pushState Dec 27 16:54:27 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:27 volumio volumio[701]: info: ------------------------------ 39ms Dec 27 16:54:27 volumio volumio[701]: info: ------------------------------ 40ms Dec 27 16:54:27 volumio volumio[701]: info: Clearing queue after UPNP request Dec 27 16:54:28 volumio volumio[701]: info: CoreStateMachine::ClearQueue Dec 27 16:54:28 volumio volumio[701]: info: CoreStateMachine::stop Dec 27 16:54:28 volumio volumio[701]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 16:54:28 volumio volumio[701]: info: CorePlayQueue::clearPlayQueue Dec 27 16:54:28 volumio volumio[701]: info: CorePlayQueue::saveQueue Dec 27 16:54:28 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:28 volumio volumio[701]: info: CoreCommandRouter::volumioPushQueue Dec 27 16:54:28 volumio volumio[701]: info: Dec 27 16:54:28 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:28 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:28 volumio volumio[701]: error: updateQueue error: null Dec 27 16:54:28 volumio volumio[701]: info: ------------------------------ 13ms Dec 27 16:54:28 volumio volumio[701]: info: Starting UPNP Playback Dec 27 16:54:28 volumio volumio[701]: info: Preparing playback through UPNP Dec 27 16:54:28 volumio volumio[701]: info: CoreCommandRouter::volumioGetState Dec 27 16:54:28 volumio volumio[701]: info: CorePlayQueue::getTrack 0 Dec 27 16:54:28 volumio volumio[701]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 27 16:54:28 volumio volumio[701]: info: Dec 27 16:54:28 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:28 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:28 volumio volumio[701]: info: ------------------------------ 3ms Dec 27 16:54:28 volumio volumio[701]: info: Dec 27 16:54:28 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:28 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:28 volumio volumio[701]: info: ------------------------------ 3ms Dec 27 16:54:28 volumio volumio[701]: info: Dec 27 16:54:28 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:28 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:28 volumio volumio[701]: info: ------------------------------ 2ms Dec 27 16:54:28 volumio volumio[701]: info: Dec 27 16:54:28 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:28 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:28 volumio volumio[701]: info: ------------------------------ 3ms Dec 27 16:54:28 volumio volumio[701]: info: Dec 27 16:54:28 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:28 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:28 volumio volumio[701]: info: ------------------------------ 3ms Dec 27 16:54:28 volumio volumio[701]: info: Dec 27 16:54:28 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:28 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:28 volumio volumio[701]: info: ------------------------------ 3ms Dec 27 16:54:28 volumio volumio[701]: info: Dec 27 16:54:28 volumio volumio[701]: ---------------------------- MPD announces state update: player Dec 27 16:54:28 volumio volumio[701]: info: ControllerMpd::getState Dec 27 16:54:28 volumio volumio[701]: verbose: ControllerMpd::sendMpdCommand status Dec 27 16:54:28 volumio volumio[701]: info: sendMpdCommand status took 2 milliseconds Dec 27 16:54:28 volumio volumio[701]: verbose: ControllerMpd::parseState Dec 27 16:54:28 volumio volumio[701]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 16:54:28 volumio volumio[701]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 27 16:54:28 volumio volumio[701]: verbose: ControllerMpd::parseTrackInfo Dec 27 16:54:28 volumio volumio[701]: info: ControllerMpd::pushState Dec 27 16:54:28 volumio volumio[701]: info: CoreCommandRouter::servicePushState Dec 27 16:54:28 volumio volumio[701]: info: CoreStateMachine::pushState Dec 27 16:54:28 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:28 volumio volumio[701]: info: CorePlayQueue::getTrack 0 Dec 27 16:54:28 volumio volumio[701]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Burning","artist":"Maria Arredondo","album":"Not Going Under","uri":"http://aqqmusic.tc.qq.com/M800002mHZz81RIho1.mp3?guid=000000005307aef90000019af43fe5cf&vkey=8BEFAB84217C9D7F3F983BD88B5327396FECCBA6DC26FF717C8B0FA5B4BD123AC84B6DE295C61C9AEE9861171ED60112DF99DE4A0FA7A21A__v2b9abf9e&uin=905090038&redirect=1&fromtag=111093","trackType":""} Dec 27 16:54:28 volumio volumio[701]: verbose: CURRENT POSITION 0 Dec 27 16:54:28 volumio volumio[701]: info: CoreStateMachine::syncState stateService stop Dec 27 16:54:28 volumio volumio[701]: info: CoreStateMachine::syncState currentStatus stop Dec 27 16:54:28 volumio volumio[701]: info: CoreStateMachine::pushState Dec 27 16:54:28 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:28 volumio volumio[701]: info: No code Dec 27 16:54:28 volumio volumio[701]: info: CoreStateMachine::pushState Dec 27 16:54:28 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:28 volumio volumio[701]: info: ------------------------------ 24ms Dec 27 16:54:28 volumio volumio[701]: info: Clearing queue after UPNP request Dec 27 16:54:29 volumio volumio[701]: info: CoreStateMachine::ClearQueue Dec 27 16:54:29 volumio volumio[701]: info: CoreStateMachine::stop Dec 27 16:54:29 volumio volumio[701]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 16:54:29 volumio volumio[701]: info: CorePlayQueue::clearPlayQueue Dec 27 16:54:29 volumio volumio[701]: info: CorePlayQueue::saveQueue Dec 27 16:54:29 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:29 volumio volumio[701]: info: CoreCommandRouter::volumioPushQueue Dec 27 16:54:29 volumio volumio[701]: info: Dec 27 16:54:29 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:29 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:29 volumio volumio[701]: error: updateQueue error: null Dec 27 16:54:29 volumio volumio[701]: info: ------------------------------ 3ms Dec 27 16:54:29 volumio volumio[701]: info: Starting UPNP Playback Dec 27 16:54:29 volumio volumio[701]: info: Preparing playback through UPNP Dec 27 16:54:29 volumio volumio[701]: info: CoreCommandRouter::volumioGetState Dec 27 16:54:29 volumio volumio[701]: info: CorePlayQueue::getTrack 0 Dec 27 16:54:29 volumio volumio[701]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 27 16:54:29 volumio volumio[701]: info: Dec 27 16:54:29 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:29 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:29 volumio volumio[701]: info: ------------------------------ 2ms Dec 27 16:54:29 volumio volumio[701]: info: Dec 27 16:54:29 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:29 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:29 volumio volumio[701]: info: ------------------------------ 1ms Dec 27 16:54:29 volumio volumio[701]: info: Dec 27 16:54:29 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:29 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:29 volumio volumio[701]: info: ------------------------------ 2ms Dec 27 16:54:29 volumio volumio[701]: info: Dec 27 16:54:29 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:29 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:29 volumio volumio[701]: info: ------------------------------ 2ms Dec 27 16:54:29 volumio volumio[701]: info: Dec 27 16:54:29 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:29 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:29 volumio volumio[701]: info: ------------------------------ 7ms Dec 27 16:54:29 volumio volumio[701]: info: Dec 27 16:54:29 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:29 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:29 volumio volumio[701]: info: ------------------------------ 2ms Dec 27 16:54:29 volumio volumio[701]: info: Dec 27 16:54:29 volumio volumio[701]: ---------------------------- MPD announces state update: player Dec 27 16:54:29 volumio volumio[701]: info: ControllerMpd::getState Dec 27 16:54:29 volumio volumio[701]: verbose: ControllerMpd::sendMpdCommand status Dec 27 16:54:29 volumio volumio[701]: info: sendMpdCommand status took 1 milliseconds Dec 27 16:54:29 volumio volumio[701]: verbose: ControllerMpd::parseState Dec 27 16:54:29 volumio volumio[701]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 16:54:29 volumio volumio[701]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 27 16:54:29 volumio volumio[701]: verbose: ControllerMpd::parseTrackInfo Dec 27 16:54:29 volumio volumio[701]: info: ControllerMpd::pushState Dec 27 16:54:29 volumio volumio[701]: info: CoreCommandRouter::servicePushState Dec 27 16:54:29 volumio volumio[701]: info: CoreStateMachine::pushState Dec 27 16:54:29 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:29 volumio volumio[701]: info: CorePlayQueue::getTrack 0 Dec 27 16:54:29 volumio volumio[701]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"人间烟火","artist":"程响","album":"人间烟火","uri":"http://aqqmusic.tc.qq.com/M800000ISy5P4UcCyL.mp3?guid=000000005307aef90000019af43fe5cf&vkey=08E58BBE7D08BE5E7D80DA16C00B70649039FA4E51E08EBF8618BE9A97A86B5C9AF7B6421B4A69EA22BEEB60F584739517760D9AB711C32C__v2b9ab707&uin=905090038&src=M800004bfQ2t3H8Cn0.mp3&redirect=1&fromtag=111093","trackType":""} Dec 27 16:54:29 volumio volumio[701]: verbose: CURRENT POSITION 0 Dec 27 16:54:29 volumio volumio[701]: info: CoreStateMachine::syncState stateService stop Dec 27 16:54:29 volumio volumio[701]: info: CoreStateMachine::syncState currentStatus stop Dec 27 16:54:29 volumio volumio[701]: info: CoreStateMachine::pushState Dec 27 16:54:29 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:29 volumio volumio[701]: info: No code Dec 27 16:54:29 volumio volumio[701]: info: CoreStateMachine::pushState Dec 27 16:54:29 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:29 volumio volumio[701]: info: ------------------------------ 11ms Dec 27 16:54:30 volumio volumio[701]: info: Clearing queue after UPNP request Dec 27 16:54:30 volumio volumio[701]: info: CoreStateMachine::ClearQueue Dec 27 16:54:30 volumio volumio[701]: info: CoreStateMachine::stop Dec 27 16:54:30 volumio volumio[701]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 16:54:30 volumio volumio[701]: info: CorePlayQueue::clearPlayQueue Dec 27 16:54:30 volumio volumio[701]: info: CorePlayQueue::saveQueue Dec 27 16:54:30 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:30 volumio volumio[701]: info: CoreCommandRouter::volumioPushQueue Dec 27 16:54:30 volumio volumio[701]: info: Dec 27 16:54:30 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:30 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:30 volumio volumio[701]: error: updateQueue error: null Dec 27 16:54:30 volumio volumio[701]: info: ------------------------------ 2ms Dec 27 16:54:30 volumio volumio[701]: info: Starting UPNP Playback Dec 27 16:54:30 volumio volumio[701]: info: Preparing playback through UPNP Dec 27 16:54:30 volumio volumio[701]: info: CoreCommandRouter::volumioGetState Dec 27 16:54:30 volumio volumio[701]: info: CorePlayQueue::getTrack 0 Dec 27 16:54:30 volumio volumio[701]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 27 16:54:30 volumio volumio[701]: info: Dec 27 16:54:30 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:30 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:30 volumio volumio[701]: info: ------------------------------ 1ms Dec 27 16:54:30 volumio volumio[701]: info: Dec 27 16:54:30 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:30 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:30 volumio volumio[701]: info: ------------------------------ 2ms Dec 27 16:54:30 volumio volumio[701]: info: Dec 27 16:54:30 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:30 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:30 volumio volumio[701]: info: ------------------------------ 2ms Dec 27 16:54:30 volumio volumio[701]: info: Dec 27 16:54:30 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:30 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:30 volumio volumio[701]: info: ------------------------------ 1ms Dec 27 16:54:30 volumio volumio[701]: info: Dec 27 16:54:30 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:30 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:30 volumio volumio[701]: info: ------------------------------ 2ms Dec 27 16:54:30 volumio volumio[701]: info: Dec 27 16:54:30 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:30 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:30 volumio volumio[701]: info: ------------------------------ 1ms Dec 27 16:54:31 volumio volumio[701]: info: Dec 27 16:54:31 volumio volumio[701]: ---------------------------- MPD announces state update: player Dec 27 16:54:31 volumio volumio[701]: info: ControllerMpd::getState Dec 27 16:54:31 volumio volumio[701]: verbose: ControllerMpd::sendMpdCommand status Dec 27 16:54:31 volumio volumio[701]: info: Dec 27 16:54:31 volumio volumio[701]: ---------------------------- MPD announces system playlist update Dec 27 16:54:31 volumio volumio[701]: info: Ignoring MPD Status Update Dec 27 16:54:31 volumio volumio[701]: info: Dec 27 16:54:31 volumio volumio[701]: ---------------------------- MPD announces state update: player Dec 27 16:54:31 volumio volumio[701]: info: sendMpdCommand status took 26 milliseconds Dec 27 16:54:31 volumio volumio[701]: info: ControllerMpd::getState Dec 27 16:54:31 volumio volumio[701]: verbose: ControllerMpd::sendMpdCommand status Dec 27 16:54:31 volumio volumio[701]: verbose: ControllerMpd::parseState Dec 27 16:54:31 volumio volumio[701]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 16:54:31 volumio volumio[701]: info: ------------------------------ 8ms Dec 27 16:54:31 volumio volumio[701]: info: sendMpdCommand status took 3 milliseconds Dec 27 16:54:31 volumio volumio[701]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 27 16:54:31 volumio volumio[701]: verbose: ControllerMpd::parseState Dec 27 16:54:31 volumio volumio[701]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 16:54:31 volumio volumio[701]: verbose: ControllerMpd::parseTrackInfo Dec 27 16:54:31 volumio volumio[701]: info: ControllerMpd::pushState Dec 27 16:54:31 volumio volumio[701]: info: CoreCommandRouter::servicePushState Dec 27 16:54:31 volumio volumio[701]: verbose: In UPNP mode Dec 27 16:54:31 volumio volumio[701]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":239,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"锦鲤抄","artist":"腾格尔","album":"锦鲤抄","uri":"http://aqqmusic.tc.qq.com/M8000000kdZG2qOpTv.mp3?guid=000000005307aef90000019af43fe5cf&vkey=B57DE79D7781B706DAD9D45827125E263637FED623440BA696A56A634FFF0BAAB0E47564DD7A1CEC289F119EA50444881F690F93D4E86565__v2b9aac76&uin=905090038&src=M80000292BaS3JJGSU.mp3&redirect=1&fromtag=111093","trackType":""} Dec 27 16:54:31 volumio volumio[701]: verbose: CURRENT POSITION 0 Dec 27 16:54:31 volumio volumio[701]: info: CoreStateMachine::syncState stateService play Dec 27 16:54:31 volumio volumio[701]: info: CoreStateMachine::syncState currentStatus stop Dec 27 16:54:31 volumio volumio[701]: info: ------------------------------ 30ms Dec 27 16:54:31 volumio volumio[701]: info: sendMpdCommand playlistinfo took 5 milliseconds Dec 27 16:54:31 volumio volumio[701]: verbose: ControllerMpd::parseTrackInfo Dec 27 16:54:31 volumio volumio[701]: info: ControllerMpd::pushState Dec 27 16:54:31 volumio volumio[701]: info: CoreCommandRouter::servicePushState Dec 27 16:54:31 volumio volumio[701]: verbose: In UPNP mode Dec 27 16:54:31 volumio volumio[701]: verbose: STATE SERVICE {"status":"play","position":0,"seek":881,"duration":239,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"锦鲤抄","artist":"腾格尔","album":"锦鲤抄","uri":"http://aqqmusic.tc.qq.com/M8000000kdZG2qOpTv.mp3?guid=000000005307aef90000019af43fe5cf&vkey=B57DE79D7781B706DAD9D45827125E263637FED623440BA696A56A634FFF0BAAB0E47564DD7A1CEC289F119EA50444881F690F93D4E86565__v2b9aac76&uin=905090038&src=M80000292BaS3JJGSU.mp3&redirect=1&fromtag=111093","trackType":""} Dec 27 16:54:31 volumio volumio[701]: verbose: CURRENT POSITION 0 Dec 27 16:54:31 volumio volumio[701]: info: CoreStateMachine::syncState stateService play Dec 27 16:54:31 volumio volumio[701]: info: CoreStateMachine::syncState currentStatus play Dec 27 16:54:31 volumio volumio[701]: info: Received an update from plugin. extracting info from payload Dec 27 16:54:31 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 27 16:54:31 volumio volumio[701]: info: CoreStateMachine::pushState Dec 27 16:54:31 volumio volumio[701]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 16:54:31 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:31 volumio volumio[701]: info: CoreStateMachine::pushState Dec 27 16:54:31 volumio volumio[701]: info: CoreCommandRouter::volumioPushState Dec 27 16:54:31 volumio volumio[701]: info: ------------------------------ 21ms Dec 27 16:54:31 volumio volumio[701]: info: CoreCommandRouter::volumioGetQueue Dec 27 16:54:31 volumio volumio[701]: info: CoreStateMachine::getQueue Dec 27 16:54:31 volumio volumio[701]: info: CorePlayQueue::getQueue Dec 27 16:54:31 volumio volumio[701]: info: CoreCommandRouter::volumioGetQueue Dec 27 16:54:31 volumio volumio[701]: info: CoreStateMachine::getQueue Dec 27 16:54:31 volumio volumio[701]: info: CorePlayQueue::getQueue Dec 27 16:54:33 volumio kernel: hwmon hwmon1: Voltage normalised Dec 27 16:54:48 volumio volumio[701]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 27 16:54:48 volumio volumio[701]: Error: connect ETIMEDOUT 108.160.166.9:443 Dec 27 16:54:48 volumio volumio[701]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Dec 27 16:54:48 volumio volumio[701]: errno: -110, Dec 27 16:54:48 volumio volumio[701]: code: 'ETIMEDOUT', Dec 27 16:54:48 volumio volumio[701]: syscall: 'connect', Dec 27 16:54:48 volumio volumio[701]: address: '108.160.166.9', Dec 27 16:54:48 volumio volumio[701]: port: 443 Dec 27 16:54:48 volumio volumio[701]: } Dec 27 16:54:48 volumio volumio[701]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 27 16:54:48 volumio sudo[8409]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-27 16:53 Dec 27 16:54:48 volumio sudo[8409]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="9552505bba4239c163ac9ee7b0b87a56e0dd0574" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="3d3fc502273a5c173ba19f72c1a952bdb2bdd3b0" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 Feb 2024 06:28:23 PM CET" VOLUMIO_VERSION="3.616" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b343c71baf05cbc21bf1a530c8e51fc1"