-- Logs begin at Thu 2019-02-14 18:11:59 CST, end at Mon 2024-07-08 14:22:21 CST. -- Jul 08 14:21:13 volumio-1 volumio[895]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E9%94%A6%E9%9B%B6/%E6%A1%83%E8%8A%B1%E7%AC%91/292b2461-3241-4d64-b9b7-0f6fcc103a0d.jpg' Jul 08 14:21:13 volumio-1 volumio[895]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 08 14:21:13 volumio-1 volumio[895]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 08 14:21:13 volumio-1 volumio[895]: info: Discovery: Getting this device information Jul 08 14:21:13 volumio-1 volumio[895]: info: CoreCommandRouter::volumioGetState Jul 08 14:21:13 volumio-1 volumio[895]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 08 14:21:17 volumio-1 volumio[895]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 08 14:21:17 volumio-1 volumio[895]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jul 08 14:21:17 volumio-1 volumio[895]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 08 14:21:17 volumio-1 volumio[895]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 08 14:21:17 volumio-1 volumio[895]: info: Getting Alsa Cards List without I2S DAC Jul 08 14:21:17 volumio-1 volumio[895]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Jul 08 14:21:17 volumio-1 volumio[895]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 08 14:21:17 volumio-1 volumio[895]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 08 14:21:17 volumio-1 volumio[895]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 08 14:21:17 volumio-1 volumio[895]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 08 14:21:17 volumio-1 volumio[895]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 08 14:21:17 volumio-1 volumio[895]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 08 14:21:17 volumio-1 volumio[895]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 08 14:21:17 volumio-1 volumio[895]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 08 14:21:17 volumio-1 volumio[895]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jul 08 14:21:17 volumio-1 volumio[895]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 08 14:21:23 volumio-1 volumio[895]: info: CALLMETHOD: audio_interface alsa_controller saveResamplingOpts [object Object] Jul 08 14:21:23 volumio-1 volumio[895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveResamplingOpts Jul 08 14:21:23 volumio-1 volumio[895]: info: CoreCommandRouter::executeOnPlugin: mpd , saveResampleOptions Jul 08 14:21:23 volumio-1 sudo[3110]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 08 14:21:23 volumio-1 sudo[3110]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 14:21:23 volumio-1 sudo[3110]: pam_unix(sudo:session): session closed for user root Jul 08 14:21:23 volumio-1 volumio[895]: info: MPD Permissions set Jul 08 14:21:23 volumio-1 volumio[895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 14:21:23 volumio-1 volumio[895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 14:21:23 volumio-1 volumio[895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 14:21:23 volumio-1 volumio[895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 14:21:23 volumio-1 volumio[895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 14:21:23 volumio-1 volumio[895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 14:21:23 volumio-1 sudo[3113]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 08 14:21:23 volumio-1 volumio[895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 14:21:23 volumio-1 sudo[3113]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 14:21:23 volumio-1 systemd[1]: Stopping Music Player Daemon... Jul 08 14:21:23 volumio-1 volumio[895]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 08 14:21:23 volumio-1 volumio[895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 14:21:23 volumio-1 systemd[1]: mpd.service: Succeeded. Jul 08 14:21:23 volumio-1 systemd[1]: Stopped Music Player Daemon. Jul 08 14:21:23 volumio-1 systemd[1]: Starting Music Player Daemon... Jul 08 14:21:23 volumio-1 sudo[3119]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 08 14:21:23 volumio-1 sudo[3119]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 14:21:23 volumio-1 sudo[3119]: pam_unix(sudo:session): session closed for user root Jul 08 14:21:23 volumio-1 volumio[895]: error: Upnp client error: Error: This socket has been ended by the other party Jul 08 14:21:24 volumio-1 mpd[3121]: Jul 08 14:21 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 08 14:21:24 volumio-1 systemd[1]: Started Music Player Daemon. Jul 08 14:21:24 volumio-1 sudo[3113]: pam_unix(sudo:session): session closed for user root Jul 08 14:21:24 volumio-1 volumio[895]: error: updateQueue error: null Jul 08 14:21:32 volumio-1 volumio[895]: info: Clearing queue after UPNP request Jul 08 14:21:33 volumio-1 volumio[895]: info: CoreStateMachine::ClearQueue Jul 08 14:21:33 volumio-1 volumio[895]: info: CoreStateMachine::stop Jul 08 14:21:33 volumio-1 volumio[895]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 08 14:21:33 volumio-1 volumio[895]: info: CoreStateMachine::stPlaybackTimer Jul 08 14:21:33 volumio-1 volumio[895]: info: CoreStateMachine::updateTrackBlock Jul 08 14:21:33 volumio-1 volumio[895]: info: CorePlayQueue::getTrackBlock Jul 08 14:21:33 volumio-1 volumio[895]: info: CoreStateMachine::pushState Jul 08 14:21:33 volumio-1 volumio[895]: info: CorePlayQueue::getTrack 0 Jul 08 14:21:33 volumio-1 volumio[895]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 14:21:33 volumio-1 volumio[895]: info: CoreCommandRouter::volumioPushState Jul 08 14:21:33 volumio-1 volumio[895]: info: CoreStateMachine::serviceStop Jul 08 14:21:33 volumio-1 volumio[895]: info: CorePlayQueue::getTrack 0 Jul 08 14:21:33 volumio-1 volumio[895]: info: ControllerMpd::stop Jul 08 14:21:33 volumio-1 volumio[895]: verbose: ControllerMpd::sendMpdCommand stop Jul 08 14:21:33 volumio-1 volumio[895]: info: CorePlayQueue::clearPlayQueue Jul 08 14:21:33 volumio-1 volumio[895]: info: CorePlayQueue::saveQueue Jul 08 14:21:33 volumio-1 volumio[895]: info: CoreCommandRouter::volumioPushState Jul 08 14:21:33 volumio-1 volumio[895]: info: CoreCommandRouter::volumioPushQueue Jul 08 14:21:33 volumio-1 volumio[895]: info: Jul 08 14:21:33 volumio-1 volumio[895]: ---------------------------- MPD announces system playlist update Jul 08 14:21:33 volumio-1 volumio[895]: info: Ignoring MPD Status Update Jul 08 14:21:33 volumio-1 volumio[895]: info: sendMpdCommand stop took 12 milliseconds Jul 08 14:21:33 volumio-1 volumio[895]: error: updateQueue error: null Jul 08 14:21:33 volumio-1 volumio[895]: info: ------------------------------ 2ms Jul 08 14:21:33 volumio-1 volumio[895]: info: Starting UPNP Playback Jul 08 14:21:33 volumio-1 volumio[895]: info: Preparing playback through UPNP Jul 08 14:21:33 volumio-1 volumio[895]: info: CoreCommandRouter::volumioGetState Jul 08 14:21:33 volumio-1 volumio[895]: info: CorePlayQueue::getTrack 0 Jul 08 14:21:33 volumio-1 volumio[895]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 08 14:21:33 volumio-1 volumio[895]: info: Jul 08 14:21:33 volumio-1 volumio[895]: ---------------------------- MPD announces system playlist update Jul 08 14:21:33 volumio-1 volumio[895]: info: Ignoring MPD Status Update Jul 08 14:21:33 volumio-1 volumio[895]: info: Jul 08 14:21:33 volumio-1 volumio[895]: ---------------------------- MPD announces system playlist update Jul 08 14:21:33 volumio-1 volumio[895]: info: Ignoring MPD Status Update Jul 08 14:21:33 volumio-1 volumio[895]: info: ------------------------------ 2ms Jul 08 14:21:33 volumio-1 volumio[895]: info: ------------------------------ 1ms Jul 08 14:21:33 volumio-1 volumio[895]: info: Jul 08 14:21:33 volumio-1 volumio[895]: ---------------------------- MPD announces system playlist update Jul 08 14:21:33 volumio-1 volumio[895]: info: Ignoring MPD Status Update Jul 08 14:21:33 volumio-1 volumio[895]: info: ------------------------------ 2ms Jul 08 14:21:33 volumio-1 volumio[895]: info: Jul 08 14:21:33 volumio-1 volumio[895]: ---------------------------- MPD announces system playlist update Jul 08 14:21:33 volumio-1 volumio[895]: info: Ignoring MPD Status Update Jul 08 14:21:33 volumio-1 volumio[895]: info: Jul 08 14:21:33 volumio-1 volumio[895]: ---------------------------- MPD announces system playlist update Jul 08 14:21:33 volumio-1 volumio[895]: info: Ignoring MPD Status Update Jul 08 14:21:33 volumio-1 volumio[895]: info: ------------------------------ 2ms Jul 08 14:21:33 volumio-1 volumio[895]: info: ------------------------------ 2ms Jul 08 14:21:33 volumio-1 volumio[895]: info: Jul 08 14:21:33 volumio-1 volumio[895]: ---------------------------- MPD announces system playlist update Jul 08 14:21:33 volumio-1 volumio[895]: info: Ignoring MPD Status Update Jul 08 14:21:33 volumio-1 volumio[895]: info: ------------------------------ 1ms Jul 08 14:21:34 volumio-1 volumio[895]: info: Jul 08 14:21:34 volumio-1 volumio[895]: ---------------------------- MPD announces state update: player Jul 08 14:21:34 volumio-1 volumio[895]: info: ControllerMpd::getState Jul 08 14:21:34 volumio-1 volumio[895]: verbose: ControllerMpd::sendMpdCommand status Jul 08 14:21:34 volumio-1 volumio[895]: info: Jul 08 14:21:34 volumio-1 volumio[895]: ---------------------------- MPD announces state update: player Jul 08 14:21:34 volumio-1 volumio[895]: info: sendMpdCommand status took 2 milliseconds Jul 08 14:21:34 volumio-1 volumio[895]: info: ControllerMpd::getState Jul 08 14:21:34 volumio-1 volumio[895]: verbose: ControllerMpd::sendMpdCommand status Jul 08 14:21:34 volumio-1 volumio[895]: verbose: ControllerMpd::parseState Jul 08 14:21:34 volumio-1 volumio[895]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 14:21:34 volumio-1 volumio[895]: info: sendMpdCommand status took 2 milliseconds Jul 08 14:21:34 volumio-1 volumio[895]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 08 14:21:34 volumio-1 volumio[895]: verbose: ControllerMpd::parseState Jul 08 14:21:34 volumio-1 volumio[895]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 14:21:34 volumio-1 volumio[895]: verbose: ControllerMpd::parseTrackInfo Jul 08 14:21:34 volumio-1 volumio[895]: info: ControllerMpd::pushState Jul 08 14:21:34 volumio-1 volumio[895]: info: CoreCommandRouter::servicePushState Jul 08 14:21:34 volumio-1 volumio[895]: verbose: In UPNP mode Jul 08 14:21:34 volumio-1 volumio[895]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":231,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"客官不可以","artist":"徐良/小凌","album":"犯贱","uri":"http://aqqmusic.tc.qq.com/F0000000uGTv0yElDp.flac?guid=00000000220dc7310000018e057bcdd7&vkey=52552A3C73D12B49248BC334ABB51EFBAB7EE7CEE2AD7D6A2FFC09AE3E096C46D49D1C850422466CB2B927C1C3FCE0B46C868586AB683D38&uin=335238641&redirect=1&fromtag=111114","trackType":""} Jul 08 14:21:34 volumio-1 volumio[895]: verbose: CURRENT POSITION 0 Jul 08 14:21:34 volumio-1 volumio[895]: info: CoreStateMachine::syncState stateService play Jul 08 14:21:34 volumio-1 volumio[895]: info: CoreStateMachine::syncState currentStatus stop Jul 08 14:21:34 volumio-1 volumio[895]: info: ------------------------------ 8ms Jul 08 14:21:34 volumio-1 volumio[895]: info: Jul 08 14:21:34 volumio-1 volumio[895]: ---------------------------- MPD announces system playlist update Jul 08 14:21:34 volumio-1 volumio[895]: info: Ignoring MPD Status Update Jul 08 14:21:34 volumio-1 volumio[895]: info: Jul 08 14:21:34 volumio-1 volumio[895]: ---------------------------- MPD announces state update: player Jul 08 14:21:34 volumio-1 volumio[895]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 08 14:21:34 volumio-1 volumio[895]: info: ControllerMpd::getState Jul 08 14:21:34 volumio-1 volumio[895]: verbose: ControllerMpd::sendMpdCommand status Jul 08 14:21:34 volumio-1 volumio[895]: verbose: ControllerMpd::parseTrackInfo Jul 08 14:21:34 volumio-1 volumio[895]: info: ControllerMpd::pushState Jul 08 14:21:34 volumio-1 volumio[895]: info: CoreCommandRouter::servicePushState Jul 08 14:21:34 volumio-1 volumio[895]: verbose: In UPNP mode Jul 08 14:21:34 volumio-1 volumio[895]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":231,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"客官不可以","artist":"徐良/小凌","album":"犯贱","uri":"http://aqqmusic.tc.qq.com/F0000000uGTv0yElDp.flac?guid=00000000220dc7310000018e057bcdd7&vkey=52552A3C73D12B49248BC334ABB51EFBAB7EE7CEE2AD7D6A2FFC09AE3E096C46D49D1C850422466CB2B927C1C3FCE0B46C868586AB683D38&uin=335238641&redirect=1&fromtag=111114","trackType":""} Jul 08 14:21:34 volumio-1 volumio[895]: verbose: CURRENT POSITION 0 Jul 08 14:21:34 volumio-1 volumio[895]: info: CoreStateMachine::syncState stateService play Jul 08 14:21:34 volumio-1 volumio[895]: info: CoreStateMachine::syncState currentStatus play Jul 08 14:21:34 volumio-1 volumio[895]: info: Received an update from plugin. extracting info from payload Jul 08 14:21:34 volumio-1 volumio[895]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 08 14:21:34 volumio-1 volumio[895]: info: CoreStateMachine::pushState Jul 08 14:21:34 volumio-1 volumio[895]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 14:21:34 volumio-1 volumio[895]: info: CoreCommandRouter::volumioPushState Jul 08 14:21:34 volumio-1 volumio[895]: info: CoreStateMachine::pushState Jul 08 14:21:34 volumio-1 volumio[895]: info: CoreCommandRouter::volumioPushState Jul 08 14:21:34 volumio-1 volumio[895]: info: ------------------------------ 24ms Jul 08 14:21:34 volumio-1 volumio[895]: info: ------------------------------ 18ms Jul 08 14:21:34 volumio-1 volumio[895]: info: sendMpdCommand status took 17 milliseconds Jul 08 14:21:34 volumio-1 volumio[895]: verbose: ControllerMpd::parseState Jul 08 14:21:34 volumio-1 volumio[895]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 14:21:34 volumio-1 volumio[895]: info: CoreCommandRouter::volumioGetQueue Jul 08 14:21:34 volumio-1 volumio[895]: info: CoreStateMachine::getQueue Jul 08 14:21:34 volumio-1 volumio[895]: info: CorePlayQueue::getQueue Jul 08 14:21:34 volumio-1 volumio[895]: info: CoreCommandRouter::volumioGetQueue Jul 08 14:21:34 volumio-1 volumio[895]: info: CoreStateMachine::getQueue Jul 08 14:21:34 volumio-1 volumio[895]: info: CorePlayQueue::getQueue Jul 08 14:21:34 volumio-1 volumio[895]: info: sendMpdCommand playlistinfo took 8 milliseconds Jul 08 14:21:34 volumio-1 volumio[895]: verbose: ControllerMpd::parseTrackInfo Jul 08 14:21:34 volumio-1 volumio[895]: info: ControllerMpd::pushState Jul 08 14:21:34 volumio-1 volumio[895]: info: CoreCommandRouter::servicePushState Jul 08 14:21:34 volumio-1 volumio[895]: verbose: In UPNP mode Jul 08 14:21:34 volumio-1 volumio[895]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":231,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"664 Kbps","isStreaming":false,"title":"客官不可以","artist":"徐良","album":"犯贱","uri":"http://aqqmusic.tc.qq.com/F0000000uGTv0yElDp.flac?guid=00000000220dc7310000018e057bcdd7&vkey=52552A3C73D12B49248BC334ABB51EFBAB7EE7CEE2AD7D6A2FFC09AE3E096C46D49D1C850422466CB2B927C1C3FCE0B46C868586AB683D38&uin=335238641&redirect=1&fromtag=111114","trackType":""} Jul 08 14:21:34 volumio-1 volumio[895]: verbose: CURRENT POSITION 0 Jul 08 14:21:34 volumio-1 volumio[895]: info: CoreStateMachine::syncState stateService play Jul 08 14:21:34 volumio-1 volumio[895]: info: CoreStateMachine::syncState currentStatus play Jul 08 14:21:34 volumio-1 volumio[895]: info: Received an update from plugin. extracting info from payload Jul 08 14:21:34 volumio-1 volumio[895]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 08 14:21:34 volumio-1 volumio[895]: info: CoreStateMachine::pushState Jul 08 14:21:34 volumio-1 volumio[895]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 14:21:34 volumio-1 volumio[895]: info: CoreCommandRouter::volumioPushState Jul 08 14:21:34 volumio-1 volumio[895]: info: CoreStateMachine::pushState Jul 08 14:21:34 volumio-1 volumio[895]: info: CoreCommandRouter::volumioPushState Jul 08 14:21:34 volumio-1 volumio[895]: info: ------------------------------ 38ms Jul 08 14:21:34 volumio-1 volumio[895]: info: CoreCommandRouter::volumioGetQueue Jul 08 14:21:34 volumio-1 volumio[895]: info: CoreStateMachine::getQueue Jul 08 14:21:34 volumio-1 volumio[895]: info: CorePlayQueue::getQueue Jul 08 14:21:34 volumio-1 volumio[895]: info: CoreCommandRouter::volumioGetQueue Jul 08 14:21:34 volumio-1 volumio[895]: info: CoreStateMachine::getQueue Jul 08 14:21:34 volumio-1 volumio[895]: info: CorePlayQueue::getQueue Jul 08 14:21:34 volumio-1 volumio[895]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%BE%90%E8%89%AF/%E7%8A%AF%E8%B4%B1/0e07b2c4-f99e-46ae-871f-35f67bb60962.jpg' Jul 08 14:21:36 volumio-1 volumio[895]: info: Jul 08 14:21:36 volumio-1 volumio[895]: ---------------------------- MPD announces state update: player Jul 08 14:21:36 volumio-1 volumio[895]: info: ControllerMpd::getState Jul 08 14:21:36 volumio-1 volumio[895]: verbose: ControllerMpd::sendMpdCommand status Jul 08 14:21:36 volumio-1 volumio[895]: info: sendMpdCommand status took 1 milliseconds Jul 08 14:21:36 volumio-1 volumio[895]: verbose: ControllerMpd::parseState Jul 08 14:21:36 volumio-1 volumio[895]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 14:21:36 volumio-1 volumio[895]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 08 14:21:36 volumio-1 volumio[895]: verbose: ControllerMpd::parseTrackInfo Jul 08 14:21:36 volumio-1 volumio[895]: info: ControllerMpd::pushState Jul 08 14:21:36 volumio-1 volumio[895]: info: CoreCommandRouter::servicePushState Jul 08 14:21:36 volumio-1 volumio[895]: info: CoreStateMachine::pushState Jul 08 14:21:36 volumio-1 volumio[895]: info: CoreCommandRouter::volumioPushState Jul 08 14:21:36 volumio-1 volumio[895]: info: CorePlayQueue::getTrack 0 Jul 08 14:21:36 volumio-1 volumio[895]: 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/F0000000uGTv0yElDp.flac?guid=00000000220dc7310000018e057bcdd7&vkey=52552A3C73D12B49248BC334ABB51EFBAB7EE7CEE2AD7D6A2FFC09AE3E096C46D49D1C850422466CB2B927C1C3FCE0B46C868586AB683D38&uin=335238641&redirect=1&fromtag=111114","trackType":""} Jul 08 14:21:36 volumio-1 volumio[895]: verbose: CURRENT POSITION 0 Jul 08 14:21:36 volumio-1 volumio[895]: info: CoreStateMachine::syncState stateService stop Jul 08 14:21:36 volumio-1 volumio[895]: info: CoreStateMachine::syncState currentStatus play Jul 08 14:21:36 volumio-1 volumio[895]: info: CoreCommandRouter::volumioPushState Jul 08 14:21:36 volumio-1 volumio[895]: info: CoreStateMachine::stPlaybackTimer Jul 08 14:21:36 volumio-1 volumio[895]: info: ------------------------------ 15ms Jul 08 14:21:36 volumio-1 volumio[895]: info: CoreCommandRouter::volumioGetQueue Jul 08 14:21:36 volumio-1 volumio[895]: info: CoreStateMachine::getQueue Jul 08 14:21:36 volumio-1 volumio[895]: info: CorePlayQueue::getQueue Jul 08 14:21:36 volumio-1 volumio[895]: info: Clearing queue after UPNP request Jul 08 14:21:37 volumio-1 volumio[895]: info: CoreStateMachine::ClearQueue Jul 08 14:21:37 volumio-1 volumio[895]: info: CoreStateMachine::stop Jul 08 14:21:37 volumio-1 volumio[895]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 08 14:21:37 volumio-1 volumio[895]: info: CorePlayQueue::clearPlayQueue Jul 08 14:21:37 volumio-1 volumio[895]: info: CorePlayQueue::saveQueue Jul 08 14:21:37 volumio-1 volumio[895]: info: CoreCommandRouter::volumioPushState Jul 08 14:21:37 volumio-1 volumio[895]: info: CoreCommandRouter::volumioPushQueue Jul 08 14:21:37 volumio-1 volumio[895]: info: Jul 08 14:21:37 volumio-1 volumio[895]: ---------------------------- MPD announces system playlist update Jul 08 14:21:37 volumio-1 volumio[895]: info: Ignoring MPD Status Update Jul 08 14:21:37 volumio-1 volumio[895]: error: updateQueue error: null Jul 08 14:21:37 volumio-1 volumio[895]: info: ------------------------------ 2ms Jul 08 14:21:37 volumio-1 volumio[895]: info: Starting UPNP Playback Jul 08 14:21:37 volumio-1 volumio[895]: info: Preparing playback through UPNP Jul 08 14:21:37 volumio-1 volumio[895]: info: CoreCommandRouter::volumioGetState Jul 08 14:21:37 volumio-1 volumio[895]: info: CorePlayQueue::getTrack 0 Jul 08 14:21:37 volumio-1 volumio[895]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 08 14:21:37 volumio-1 volumio[895]: info: Jul 08 14:21:37 volumio-1 volumio[895]: ---------------------------- MPD announces system playlist update Jul 08 14:21:37 volumio-1 volumio[895]: info: Ignoring MPD Status Update Jul 08 14:21:37 volumio-1 volumio[895]: info: Jul 08 14:21:37 volumio-1 volumio[895]: ---------------------------- MPD announces system playlist update Jul 08 14:21:37 volumio-1 volumio[895]: info: Ignoring MPD Status Update Jul 08 14:21:37 volumio-1 volumio[895]: info: ------------------------------ 1ms Jul 08 14:21:37 volumio-1 volumio[895]: info: ------------------------------ 1ms Jul 08 14:21:37 volumio-1 volumio[895]: info: Jul 08 14:21:37 volumio-1 volumio[895]: ---------------------------- MPD announces system playlist update Jul 08 14:21:37 volumio-1 volumio[895]: info: Ignoring MPD Status Update Jul 08 14:21:37 volumio-1 volumio[895]: info: Jul 08 14:21:37 volumio-1 volumio[895]: ---------------------------- MPD announces system playlist update Jul 08 14:21:37 volumio-1 volumio[895]: info: Ignoring MPD Status Update Jul 08 14:21:37 volumio-1 volumio[895]: info: ------------------------------ 1ms Jul 08 14:21:37 volumio-1 volumio[895]: info: ------------------------------ 1ms Jul 08 14:21:37 volumio-1 volumio[895]: info: Jul 08 14:21:37 volumio-1 volumio[895]: ---------------------------- MPD announces system playlist update Jul 08 14:21:37 volumio-1 volumio[895]: info: Ignoring MPD Status Update Jul 08 14:21:37 volumio-1 volumio[895]: info: ------------------------------ 1ms Jul 08 14:21:37 volumio-1 volumio[895]: info: Jul 08 14:21:37 volumio-1 volumio[895]: ---------------------------- MPD announces system playlist update Jul 08 14:21:37 volumio-1 volumio[895]: info: Ignoring MPD Status Update Jul 08 14:21:37 volumio-1 volumio[895]: info: ------------------------------ 1ms Jul 08 14:21:37 volumio-1 volumio[895]: info: Jul 08 14:21:37 volumio-1 volumio[895]: ---------------------------- MPD announces state update: player Jul 08 14:21:37 volumio-1 volumio[895]: info: ControllerMpd::getState Jul 08 14:21:37 volumio-1 volumio[895]: verbose: ControllerMpd::sendMpdCommand status Jul 08 14:21:37 volumio-1 volumio[895]: info: Jul 08 14:21:37 volumio-1 volumio[895]: ---------------------------- MPD announces state update: player Jul 08 14:21:37 volumio-1 volumio[895]: info: sendMpdCommand status took 2 milliseconds Jul 08 14:21:37 volumio-1 volumio[895]: info: ControllerMpd::getState Jul 08 14:21:37 volumio-1 volumio[895]: verbose: ControllerMpd::sendMpdCommand status Jul 08 14:21:37 volumio-1 volumio[895]: verbose: ControllerMpd::parseState Jul 08 14:21:37 volumio-1 volumio[895]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 14:21:37 volumio-1 volumio[895]: info: sendMpdCommand status took 1 milliseconds Jul 08 14:21:37 volumio-1 volumio[895]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 08 14:21:37 volumio-1 volumio[895]: verbose: ControllerMpd::parseState Jul 08 14:21:37 volumio-1 volumio[895]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 14:21:37 volumio-1 volumio[895]: verbose: ControllerMpd::parseTrackInfo Jul 08 14:21:37 volumio-1 volumio[895]: info: ControllerMpd::pushState Jul 08 14:21:37 volumio-1 volumio[895]: info: CoreCommandRouter::servicePushState Jul 08 14:21:37 volumio-1 volumio[895]: verbose: In UPNP mode Jul 08 14:21:37 volumio-1 volumio[895]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":200,"samplerate":"48 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/F000003NVK0J4JMXS1.flac?guid=00000000220dc7310000018e057bcdd7&vkey=F0556F3CB330EE5594DA4D581646C875B0C6F9F9089D79044DDE3579104F83FCBC4B8CB8B276EDB431FCDCC078E40AC57AD42F54CC8099A9&uin=335238641&redirect=1&fromtag=111114","trackType":""} Jul 08 14:21:37 volumio-1 volumio[895]: verbose: CURRENT POSITION 0 Jul 08 14:21:37 volumio-1 volumio[895]: info: CoreStateMachine::syncState stateService play Jul 08 14:21:37 volumio-1 volumio[895]: info: CoreStateMachine::syncState currentStatus stop Jul 08 14:21:37 volumio-1 volumio[895]: info: ------------------------------ 7ms Jul 08 14:21:37 volumio-1 volumio[895]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 08 14:21:37 volumio-1 volumio[895]: verbose: ControllerMpd::parseTrackInfo Jul 08 14:21:37 volumio-1 volumio[895]: info: ControllerMpd::pushState Jul 08 14:21:37 volumio-1 volumio[895]: info: CoreCommandRouter::servicePushState Jul 08 14:21:37 volumio-1 volumio[895]: verbose: In UPNP mode Jul 08 14:21:37 volumio-1 volumio[895]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":200,"samplerate":"48 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/F000003NVK0J4JMXS1.flac?guid=00000000220dc7310000018e057bcdd7&vkey=F0556F3CB330EE5594DA4D581646C875B0C6F9F9089D79044DDE3579104F83FCBC4B8CB8B276EDB431FCDCC078E40AC57AD42F54CC8099A9&uin=335238641&redirect=1&fromtag=111114","trackType":""} Jul 08 14:21:37 volumio-1 volumio[895]: verbose: CURRENT POSITION 0 Jul 08 14:21:37 volumio-1 volumio[895]: info: CoreStateMachine::syncState stateService play Jul 08 14:21:37 volumio-1 volumio[895]: info: CoreStateMachine::syncState currentStatus play Jul 08 14:21:37 volumio-1 volumio[895]: info: Received an update from plugin. extracting info from payload Jul 08 14:21:37 volumio-1 volumio[895]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 08 14:21:37 volumio-1 volumio[895]: info: CoreStateMachine::pushState Jul 08 14:21:37 volumio-1 volumio[895]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 14:21:37 volumio-1 volumio[895]: info: CoreCommandRouter::volumioPushState Jul 08 14:21:37 volumio-1 volumio[895]: info: CoreStateMachine::pushState Jul 08 14:21:37 volumio-1 volumio[895]: info: CoreCommandRouter::volumioPushState Jul 08 14:21:37 volumio-1 volumio[895]: info: ------------------------------ 18ms Jul 08 14:21:37 volumio-1 volumio[895]: info: CoreCommandRouter::volumioGetQueue Jul 08 14:21:37 volumio-1 volumio[895]: info: CoreStateMachine::getQueue Jul 08 14:21:37 volumio-1 volumio[895]: info: CorePlayQueue::getQueue Jul 08 14:21:37 volumio-1 volumio[895]: info: CoreCommandRouter::volumioGetQueue Jul 08 14:21:37 volumio-1 volumio[895]: info: CoreStateMachine::getQueue Jul 08 14:21:37 volumio-1 volumio[895]: info: CorePlayQueue::getQueue Jul 08 14:21:37 volumio-1 volumio[895]: info: Jul 08 14:21:37 volumio-1 volumio[895]: ---------------------------- MPD announces system playlist update Jul 08 14:21:37 volumio-1 volumio[895]: info: Ignoring MPD Status Update Jul 08 14:21:37 volumio-1 volumio[895]: info: Jul 08 14:21:37 volumio-1 volumio[895]: ---------------------------- MPD announces state update: player Jul 08 14:21:37 volumio-1 volumio[895]: info: ControllerMpd::getState Jul 08 14:21:37 volumio-1 volumio[895]: verbose: ControllerMpd::sendMpdCommand status Jul 08 14:21:37 volumio-1 volumio[895]: info: ------------------------------ 2ms Jul 08 14:21:37 volumio-1 volumio[895]: info: sendMpdCommand status took 1 milliseconds Jul 08 14:21:37 volumio-1 volumio[895]: verbose: ControllerMpd::parseState Jul 08 14:21:37 volumio-1 volumio[895]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 14:21:37 volumio-1 volumio[895]: info: sendMpdCommand playlistinfo took 0 milliseconds Jul 08 14:21:37 volumio-1 volumio[895]: verbose: ControllerMpd::parseTrackInfo Jul 08 14:21:37 volumio-1 volumio[895]: info: ControllerMpd::pushState Jul 08 14:21:37 volumio-1 volumio[895]: info: CoreCommandRouter::servicePushState Jul 08 14:21:37 volumio-1 volumio[895]: verbose: In UPNP mode Jul 08 14:21:37 volumio-1 volumio[895]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":200,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1489 Kbps","isStreaming":false,"title":"桃花笑","artist":"锦零","album":"桃花笑","uri":"http://aqqmusic.tc.qq.com/F000003NVK0J4JMXS1.flac?guid=00000000220dc7310000018e057bcdd7&vkey=F0556F3CB330EE5594DA4D581646C875B0C6F9F9089D79044DDE3579104F83FCBC4B8CB8B276EDB431FCDCC078E40AC57AD42F54CC8099A9&uin=335238641&redirect=1&fromtag=111114","trackType":""} Jul 08 14:21:37 volumio-1 volumio[895]: verbose: CURRENT POSITION 0 Jul 08 14:21:37 volumio-1 volumio[895]: info: CoreStateMachine::syncState stateService play Jul 08 14:21:37 volumio-1 volumio[895]: info: CoreStateMachine::syncState currentStatus play Jul 08 14:21:37 volumio-1 volumio[895]: info: Received an update from plugin. extracting info from payload Jul 08 14:21:37 volumio-1 volumio[895]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 08 14:21:37 volumio-1 volumio[895]: info: CoreStateMachine::pushState Jul 08 14:21:37 volumio-1 volumio[895]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 14:21:37 volumio-1 volumio[895]: info: CoreCommandRouter::volumioPushState Jul 08 14:21:37 volumio-1 volumio[895]: info: CoreStateMachine::pushState Jul 08 14:21:37 volumio-1 volumio[895]: info: CoreCommandRouter::volumioPushState Jul 08 14:21:37 volumio-1 volumio[895]: info: ------------------------------ 20ms Jul 08 14:21:37 volumio-1 volumio[895]: info: CoreCommandRouter::volumioGetQueue Jul 08 14:21:37 volumio-1 volumio[895]: info: CoreStateMachine::getQueue Jul 08 14:21:37 volumio-1 volumio[895]: info: CorePlayQueue::getQueue Jul 08 14:21:37 volumio-1 volumio[895]: info: CoreCommandRouter::volumioGetQueue Jul 08 14:21:37 volumio-1 volumio[895]: info: CoreStateMachine::getQueue Jul 08 14:21:37 volumio-1 volumio[895]: info: CorePlayQueue::getQueue Jul 08 14:21:37 volumio-1 volumio[895]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E9%94%A6%E9%9B%B6/%E6%A1%83%E8%8A%B1%E7%AC%91/292b2461-3241-4d64-b9b7-0f6fcc103a0d.jpg' Jul 08 14:21:44 volumio-1 volumio[895]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 08 14:21:44 volumio-1 volumio[895]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 08 14:21:44 volumio-1 volumio[895]: info: Discovery: Getting this device information Jul 08 14:21:44 volumio-1 volumio[895]: info: CoreCommandRouter::volumioGetState Jul 08 14:21:44 volumio-1 volumio[895]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 08 14:21:47 volumio-1 volumio[895]: info: CALLMETHOD: audio_interface alsa_controller saveResamplingOpts [object Object] Jul 08 14:21:47 volumio-1 volumio[895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveResamplingOpts Jul 08 14:21:47 volumio-1 volumio[895]: info: CoreCommandRouter::executeOnPlugin: mpd , saveResampleOptions Jul 08 14:21:47 volumio-1 sudo[3198]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 08 14:21:47 volumio-1 sudo[3198]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 14:21:47 volumio-1 sudo[3198]: pam_unix(sudo:session): session closed for user root Jul 08 14:21:47 volumio-1 volumio[895]: info: MPD Permissions set Jul 08 14:21:47 volumio-1 volumio[895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 14:21:47 volumio-1 volumio[895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 14:21:47 volumio-1 volumio[895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 14:21:47 volumio-1 volumio[895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 14:21:47 volumio-1 volumio[895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 14:21:47 volumio-1 volumio[895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 14:21:47 volumio-1 volumio[895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 14:21:47 volumio-1 volumio[895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 14:21:47 volumio-1 sudo[3201]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 08 14:21:47 volumio-1 sudo[3201]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 14:21:47 volumio-1 volumio[895]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 08 14:21:47 volumio-1 volumio[895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 14:21:47 volumio-1 systemd[1]: Stopping Music Player Daemon... Jul 08 14:21:47 volumio-1 systemd[1]: mpd.service: Succeeded. Jul 08 14:21:47 volumio-1 systemd[1]: Stopped Music Player Daemon. Jul 08 14:21:47 volumio-1 volumio[895]: error: Upnp client error: Error: This socket has been ended by the other party Jul 08 14:21:47 volumio-1 systemd[1]: Starting Music Player Daemon... Jul 08 14:21:48 volumio-1 sudo[3207]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 08 14:21:48 volumio-1 sudo[3207]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 14:21:48 volumio-1 sudo[3207]: pam_unix(sudo:session): session closed for user root Jul 08 14:21:48 volumio-1 mpd[3209]: Jul 08 14:21 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 08 14:21:48 volumio-1 systemd[1]: Started Music Player Daemon. Jul 08 14:21:48 volumio-1 sudo[3201]: pam_unix(sudo:session): session closed for user root Jul 08 14:21:48 volumio-1 volumio[895]: error: updateQueue error: null Jul 08 14:22:02 volumio-1 volumio[895]: info: Clearing queue after UPNP request Jul 08 14:22:02 volumio-1 volumio[895]: info: CoreStateMachine::ClearQueue Jul 08 14:22:02 volumio-1 volumio[895]: info: CoreStateMachine::stop Jul 08 14:22:02 volumio-1 volumio[895]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 08 14:22:02 volumio-1 volumio[895]: info: CoreStateMachine::stPlaybackTimer Jul 08 14:22:02 volumio-1 volumio[895]: info: CoreStateMachine::updateTrackBlock Jul 08 14:22:02 volumio-1 volumio[895]: info: CorePlayQueue::getTrackBlock Jul 08 14:22:02 volumio-1 volumio[895]: info: CoreStateMachine::pushState Jul 08 14:22:02 volumio-1 volumio[895]: info: CorePlayQueue::getTrack 0 Jul 08 14:22:02 volumio-1 volumio[895]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 14:22:02 volumio-1 volumio[895]: info: CoreCommandRouter::volumioPushState Jul 08 14:22:02 volumio-1 volumio[895]: info: CoreStateMachine::serviceStop Jul 08 14:22:02 volumio-1 volumio[895]: info: CorePlayQueue::getTrack 0 Jul 08 14:22:02 volumio-1 volumio[895]: info: ControllerMpd::stop Jul 08 14:22:02 volumio-1 volumio[895]: verbose: ControllerMpd::sendMpdCommand stop Jul 08 14:22:02 volumio-1 volumio[895]: info: CorePlayQueue::clearPlayQueue Jul 08 14:22:02 volumio-1 volumio[895]: info: CorePlayQueue::saveQueue Jul 08 14:22:02 volumio-1 volumio[895]: info: CoreCommandRouter::volumioPushState Jul 08 14:22:02 volumio-1 volumio[895]: info: CoreCommandRouter::volumioPushQueue Jul 08 14:22:02 volumio-1 volumio[895]: info: Jul 08 14:22:02 volumio-1 volumio[895]: ---------------------------- MPD announces system playlist update Jul 08 14:22:02 volumio-1 volumio[895]: info: Ignoring MPD Status Update Jul 08 14:22:02 volumio-1 volumio[895]: info: sendMpdCommand stop took 16 milliseconds Jul 08 14:22:02 volumio-1 volumio[895]: error: updateQueue error: null Jul 08 14:22:02 volumio-1 volumio[895]: info: ------------------------------ 2ms Jul 08 14:22:02 volumio-1 volumio[895]: info: Starting UPNP Playback Jul 08 14:22:02 volumio-1 volumio[895]: info: Preparing playback through UPNP Jul 08 14:22:02 volumio-1 volumio[895]: info: CoreCommandRouter::volumioGetState Jul 08 14:22:02 volumio-1 volumio[895]: info: CorePlayQueue::getTrack 0 Jul 08 14:22:02 volumio-1 volumio[895]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 08 14:22:03 volumio-1 volumio[895]: info: Jul 08 14:22:03 volumio-1 volumio[895]: ---------------------------- MPD announces system playlist update Jul 08 14:22:03 volumio-1 volumio[895]: info: Ignoring MPD Status Update Jul 08 14:22:03 volumio-1 volumio[895]: info: ------------------------------ 1ms Jul 08 14:22:03 volumio-1 volumio[895]: info: Jul 08 14:22:03 volumio-1 volumio[895]: ---------------------------- MPD announces system playlist update Jul 08 14:22:03 volumio-1 volumio[895]: info: Ignoring MPD Status Update Jul 08 14:22:03 volumio-1 volumio[895]: info: ------------------------------ 1ms Jul 08 14:22:03 volumio-1 volumio[895]: info: Jul 08 14:22:03 volumio-1 volumio[895]: ---------------------------- MPD announces system playlist update Jul 08 14:22:03 volumio-1 volumio[895]: info: Ignoring MPD Status Update Jul 08 14:22:03 volumio-1 volumio[895]: info: ------------------------------ 1ms Jul 08 14:22:03 volumio-1 volumio[895]: info: Jul 08 14:22:03 volumio-1 volumio[895]: ---------------------------- MPD announces system playlist update Jul 08 14:22:03 volumio-1 volumio[895]: info: Ignoring MPD Status Update Jul 08 14:22:03 volumio-1 volumio[895]: info: ------------------------------ 1ms Jul 08 14:22:03 volumio-1 volumio[895]: info: Jul 08 14:22:03 volumio-1 volumio[895]: ---------------------------- MPD announces system playlist update Jul 08 14:22:03 volumio-1 volumio[895]: info: Ignoring MPD Status Update Jul 08 14:22:03 volumio-1 volumio[895]: info: ------------------------------ 1ms Jul 08 14:22:03 volumio-1 volumio[895]: info: Jul 08 14:22:03 volumio-1 volumio[895]: ---------------------------- MPD announces system playlist update Jul 08 14:22:03 volumio-1 volumio[895]: info: Ignoring MPD Status Update Jul 08 14:22:03 volumio-1 volumio[895]: info: ------------------------------ 1ms Jul 08 14:22:04 volumio-1 volumio[895]: info: Jul 08 14:22:04 volumio-1 volumio[895]: ---------------------------- MPD announces state update: player Jul 08 14:22:04 volumio-1 volumio[895]: info: ControllerMpd::getState Jul 08 14:22:04 volumio-1 volumio[895]: verbose: ControllerMpd::sendMpdCommand status Jul 08 14:22:04 volumio-1 volumio[895]: info: Jul 08 14:22:04 volumio-1 volumio[895]: ---------------------------- MPD announces state update: player Jul 08 14:22:04 volumio-1 volumio[895]: info: sendMpdCommand status took 1 milliseconds Jul 08 14:22:04 volumio-1 volumio[895]: info: ControllerMpd::getState Jul 08 14:22:04 volumio-1 volumio[895]: verbose: ControllerMpd::sendMpdCommand status Jul 08 14:22:04 volumio-1 volumio[895]: verbose: ControllerMpd::parseState Jul 08 14:22:04 volumio-1 volumio[895]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 14:22:04 volumio-1 volumio[895]: info: sendMpdCommand status took 1 milliseconds Jul 08 14:22:04 volumio-1 volumio[895]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 08 14:22:04 volumio-1 volumio[895]: verbose: ControllerMpd::parseState Jul 08 14:22:04 volumio-1 volumio[895]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 14:22:04 volumio-1 volumio[895]: verbose: ControllerMpd::parseTrackInfo Jul 08 14:22:04 volumio-1 volumio[895]: info: ControllerMpd::pushState Jul 08 14:22:04 volumio-1 volumio[895]: info: CoreCommandRouter::servicePushState Jul 08 14:22:04 volumio-1 volumio[895]: verbose: In UPNP mode Jul 08 14:22:04 volumio-1 volumio[895]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":231,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"客官不可以","artist":"徐良/小凌","album":"犯贱","uri":"http://aqqmusic.tc.qq.com/F0000000uGTv0yElDp.flac?guid=00000000220dc7310000018e057bcdd7&vkey=52552A3C73D12B49248BC334ABB51EFBAB7EE7CEE2AD7D6A2FFC09AE3E096C46D49D1C850422466CB2B927C1C3FCE0B46C868586AB683D38&uin=335238641&redirect=1&fromtag=111114","trackType":""} Jul 08 14:22:04 volumio-1 volumio[895]: verbose: CURRENT POSITION 0 Jul 08 14:22:04 volumio-1 volumio[895]: info: CoreStateMachine::syncState stateService play Jul 08 14:22:04 volumio-1 volumio[895]: info: CoreStateMachine::syncState currentStatus stop Jul 08 14:22:04 volumio-1 volumio[895]: info: ------------------------------ 6ms Jul 08 14:22:04 volumio-1 volumio[895]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 08 14:22:04 volumio-1 volumio[895]: verbose: ControllerMpd::parseTrackInfo Jul 08 14:22:04 volumio-1 volumio[895]: info: ControllerMpd::pushState Jul 08 14:22:04 volumio-1 volumio[895]: info: CoreCommandRouter::servicePushState Jul 08 14:22:04 volumio-1 volumio[895]: verbose: In UPNP mode Jul 08 14:22:04 volumio-1 volumio[895]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":231,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"客官不可以","artist":"徐良/小凌","album":"犯贱","uri":"http://aqqmusic.tc.qq.com/F0000000uGTv0yElDp.flac?guid=00000000220dc7310000018e057bcdd7&vkey=52552A3C73D12B49248BC334ABB51EFBAB7EE7CEE2AD7D6A2FFC09AE3E096C46D49D1C850422466CB2B927C1C3FCE0B46C868586AB683D38&uin=335238641&redirect=1&fromtag=111114","trackType":""} Jul 08 14:22:04 volumio-1 volumio[895]: verbose: CURRENT POSITION 0 Jul 08 14:22:04 volumio-1 volumio[895]: info: CoreStateMachine::syncState stateService play Jul 08 14:22:04 volumio-1 volumio[895]: info: CoreStateMachine::syncState currentStatus play Jul 08 14:22:04 volumio-1 volumio[895]: info: Received an update from plugin. extracting info from payload Jul 08 14:22:04 volumio-1 volumio[895]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 08 14:22:04 volumio-1 volumio[895]: info: CoreStateMachine::pushState Jul 08 14:22:04 volumio-1 volumio[895]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 14:22:04 volumio-1 volumio[895]: info: CoreCommandRouter::volumioPushState Jul 08 14:22:04 volumio-1 volumio[895]: info: CoreStateMachine::pushState Jul 08 14:22:04 volumio-1 volumio[895]: info: CoreCommandRouter::volumioPushState Jul 08 14:22:04 volumio-1 volumio[895]: info: ------------------------------ 19ms Jul 08 14:22:04 volumio-1 volumio[895]: info: CoreCommandRouter::volumioGetQueue Jul 08 14:22:04 volumio-1 volumio[895]: info: CoreStateMachine::getQueue Jul 08 14:22:04 volumio-1 volumio[895]: info: CorePlayQueue::getQueue Jul 08 14:22:04 volumio-1 volumio[895]: info: CoreCommandRouter::volumioGetQueue Jul 08 14:22:04 volumio-1 volumio[895]: info: CoreStateMachine::getQueue Jul 08 14:22:04 volumio-1 volumio[895]: info: CorePlayQueue::getQueue Jul 08 14:22:04 volumio-1 volumio[895]: info: Jul 08 14:22:04 volumio-1 volumio[895]: ---------------------------- MPD announces system playlist update Jul 08 14:22:04 volumio-1 volumio[895]: info: Ignoring MPD Status Update Jul 08 14:22:04 volumio-1 volumio[895]: info: Jul 08 14:22:04 volumio-1 volumio[895]: ---------------------------- MPD announces state update: player Jul 08 14:22:04 volumio-1 volumio[895]: info: ControllerMpd::getState Jul 08 14:22:04 volumio-1 volumio[895]: verbose: ControllerMpd::sendMpdCommand status Jul 08 14:22:04 volumio-1 volumio[895]: info: ------------------------------ 2ms Jul 08 14:22:04 volumio-1 volumio[895]: info: sendMpdCommand status took 1 milliseconds Jul 08 14:22:04 volumio-1 volumio[895]: verbose: ControllerMpd::parseState Jul 08 14:22:04 volumio-1 volumio[895]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 14:22:04 volumio-1 volumio[895]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 08 14:22:04 volumio-1 volumio[895]: verbose: ControllerMpd::parseTrackInfo Jul 08 14:22:04 volumio-1 volumio[895]: info: ControllerMpd::pushState Jul 08 14:22:04 volumio-1 volumio[895]: info: CoreCommandRouter::servicePushState Jul 08 14:22:04 volumio-1 volumio[895]: verbose: In UPNP mode Jul 08 14:22:04 volumio-1 volumio[895]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":231,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"594 Kbps","isStreaming":false,"title":"客官不可以","artist":"徐良","album":"犯贱","uri":"http://aqqmusic.tc.qq.com/F0000000uGTv0yElDp.flac?guid=00000000220dc7310000018e057bcdd7&vkey=52552A3C73D12B49248BC334ABB51EFBAB7EE7CEE2AD7D6A2FFC09AE3E096C46D49D1C850422466CB2B927C1C3FCE0B46C868586AB683D38&uin=335238641&redirect=1&fromtag=111114","trackType":""} Jul 08 14:22:04 volumio-1 volumio[895]: verbose: CURRENT POSITION 0 Jul 08 14:22:04 volumio-1 volumio[895]: info: CoreStateMachine::syncState stateService play Jul 08 14:22:04 volumio-1 volumio[895]: info: CoreStateMachine::syncState currentStatus play Jul 08 14:22:04 volumio-1 volumio[895]: info: Received an update from plugin. extracting info from payload Jul 08 14:22:04 volumio-1 volumio[895]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 08 14:22:04 volumio-1 volumio[895]: info: CoreStateMachine::pushState Jul 08 14:22:04 volumio-1 volumio[895]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 14:22:04 volumio-1 volumio[895]: info: CoreCommandRouter::volumioPushState Jul 08 14:22:04 volumio-1 volumio[895]: info: CoreStateMachine::pushState Jul 08 14:22:04 volumio-1 volumio[895]: info: CoreCommandRouter::volumioPushState Jul 08 14:22:04 volumio-1 volumio[895]: info: ------------------------------ 16ms Jul 08 14:22:04 volumio-1 volumio[895]: info: CoreCommandRouter::volumioGetQueue Jul 08 14:22:04 volumio-1 volumio[895]: info: CoreStateMachine::getQueue Jul 08 14:22:04 volumio-1 volumio[895]: info: CorePlayQueue::getQueue Jul 08 14:22:04 volumio-1 volumio[895]: info: CoreCommandRouter::volumioGetQueue Jul 08 14:22:04 volumio-1 volumio[895]: info: CoreStateMachine::getQueue Jul 08 14:22:04 volumio-1 volumio[895]: info: CorePlayQueue::getQueue Jul 08 14:22:11 volumio-1 volumio[895]: info: Jul 08 14:22:11 volumio-1 volumio[895]: ---------------------------- MPD announces state update: player Jul 08 14:22:11 volumio-1 volumio[895]: info: ControllerMpd::getState Jul 08 14:22:11 volumio-1 volumio[895]: verbose: ControllerMpd::sendMpdCommand status Jul 08 14:22:11 volumio-1 volumio[895]: info: sendMpdCommand status took 1 milliseconds Jul 08 14:22:11 volumio-1 volumio[895]: verbose: ControllerMpd::parseState Jul 08 14:22:11 volumio-1 volumio[895]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 14:22:11 volumio-1 volumio[895]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 08 14:22:11 volumio-1 volumio[895]: verbose: ControllerMpd::parseTrackInfo Jul 08 14:22:11 volumio-1 volumio[895]: info: ControllerMpd::pushState Jul 08 14:22:11 volumio-1 volumio[895]: info: CoreCommandRouter::servicePushState Jul 08 14:22:11 volumio-1 volumio[895]: info: CoreStateMachine::pushState Jul 08 14:22:11 volumio-1 volumio[895]: info: CoreCommandRouter::volumioPushState Jul 08 14:22:11 volumio-1 volumio[895]: info: CorePlayQueue::getTrack 0 Jul 08 14:22:11 volumio-1 volumio[895]: 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/F0000000uGTv0yElDp.flac?guid=00000000220dc7310000018e057bcdd7&vkey=52552A3C73D12B49248BC334ABB51EFBAB7EE7CEE2AD7D6A2FFC09AE3E096C46D49D1C850422466CB2B927C1C3FCE0B46C868586AB683D38&uin=335238641&redirect=1&fromtag=111114","trackType":""} Jul 08 14:22:11 volumio-1 volumio[895]: verbose: CURRENT POSITION 0 Jul 08 14:22:11 volumio-1 volumio[895]: info: CoreStateMachine::syncState stateService stop Jul 08 14:22:11 volumio-1 volumio[895]: info: CoreStateMachine::syncState currentStatus play Jul 08 14:22:11 volumio-1 volumio[895]: info: CoreCommandRouter::volumioPushState Jul 08 14:22:11 volumio-1 volumio[895]: info: CoreStateMachine::stPlaybackTimer Jul 08 14:22:11 volumio-1 volumio[895]: info: ------------------------------ 16ms Jul 08 14:22:11 volumio-1 volumio[895]: info: CoreCommandRouter::volumioGetQueue Jul 08 14:22:11 volumio-1 volumio[895]: info: CoreStateMachine::getQueue Jul 08 14:22:11 volumio-1 volumio[895]: info: CorePlayQueue::getQueue Jul 08 14:22:11 volumio-1 volumio[895]: info: Clearing queue after UPNP request Jul 08 14:22:12 volumio-1 volumio[895]: info: CoreStateMachine::ClearQueue Jul 08 14:22:12 volumio-1 volumio[895]: info: CoreStateMachine::stop Jul 08 14:22:12 volumio-1 volumio[895]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 08 14:22:12 volumio-1 volumio[895]: info: CorePlayQueue::clearPlayQueue Jul 08 14:22:12 volumio-1 volumio[895]: info: CorePlayQueue::saveQueue Jul 08 14:22:12 volumio-1 volumio[895]: info: CoreCommandRouter::volumioPushState Jul 08 14:22:12 volumio-1 volumio[895]: info: CoreCommandRouter::volumioPushQueue Jul 08 14:22:12 volumio-1 volumio[895]: info: Jul 08 14:22:12 volumio-1 volumio[895]: ---------------------------- MPD announces system playlist update Jul 08 14:22:12 volumio-1 volumio[895]: info: Ignoring MPD Status Update Jul 08 14:22:12 volumio-1 volumio[895]: error: updateQueue error: null Jul 08 14:22:12 volumio-1 volumio[895]: info: ------------------------------ 2ms Jul 08 14:22:12 volumio-1 volumio[895]: info: Starting UPNP Playback Jul 08 14:22:12 volumio-1 volumio[895]: info: Preparing playback through UPNP Jul 08 14:22:12 volumio-1 volumio[895]: info: CoreCommandRouter::volumioGetState Jul 08 14:22:12 volumio-1 volumio[895]: info: CorePlayQueue::getTrack 0 Jul 08 14:22:12 volumio-1 volumio[895]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 08 14:22:12 volumio-1 volumio[895]: info: Jul 08 14:22:12 volumio-1 volumio[895]: ---------------------------- MPD announces system playlist update Jul 08 14:22:12 volumio-1 volumio[895]: info: Ignoring MPD Status Update Jul 08 14:22:12 volumio-1 volumio[895]: info: Jul 08 14:22:12 volumio-1 volumio[895]: ---------------------------- MPD announces system playlist update Jul 08 14:22:12 volumio-1 volumio[895]: info: Ignoring MPD Status Update Jul 08 14:22:12 volumio-1 volumio[895]: info: ------------------------------ 3ms Jul 08 14:22:12 volumio-1 volumio[895]: info: ------------------------------ 2ms Jul 08 14:22:12 volumio-1 volumio[895]: info: Jul 08 14:22:12 volumio-1 volumio[895]: ---------------------------- MPD announces system playlist update Jul 08 14:22:12 volumio-1 volumio[895]: info: Ignoring MPD Status Update Jul 08 14:22:12 volumio-1 volumio[895]: info: ------------------------------ 2ms Jul 08 14:22:12 volumio-1 volumio[895]: info: Jul 08 14:22:12 volumio-1 volumio[895]: ---------------------------- MPD announces system playlist update Jul 08 14:22:12 volumio-1 volumio[895]: info: Ignoring MPD Status Update Jul 08 14:22:12 volumio-1 volumio[895]: info: Jul 08 14:22:12 volumio-1 volumio[895]: ---------------------------- MPD announces system playlist update Jul 08 14:22:12 volumio-1 volumio[895]: info: Ignoring MPD Status Update Jul 08 14:22:12 volumio-1 volumio[895]: info: ------------------------------ 3ms Jul 08 14:22:12 volumio-1 volumio[895]: info: ------------------------------ 1ms Jul 08 14:22:12 volumio-1 volumio[895]: info: Jul 08 14:22:12 volumio-1 volumio[895]: ---------------------------- MPD announces system playlist update Jul 08 14:22:12 volumio-1 volumio[895]: info: Ignoring MPD Status Update Jul 08 14:22:12 volumio-1 volumio[895]: info: ------------------------------ 2ms Jul 08 14:22:12 volumio-1 volumio[895]: info: Jul 08 14:22:12 volumio-1 volumio[895]: ---------------------------- MPD announces state update: player Jul 08 14:22:12 volumio-1 volumio[895]: info: ControllerMpd::getState Jul 08 14:22:12 volumio-1 volumio[895]: verbose: ControllerMpd::sendMpdCommand status Jul 08 14:22:12 volumio-1 volumio[895]: info: Jul 08 14:22:12 volumio-1 volumio[895]: ---------------------------- MPD announces state update: player Jul 08 14:22:12 volumio-1 volumio[895]: info: ControllerMpd::getState Jul 08 14:22:12 volumio-1 volumio[895]: verbose: ControllerMpd::sendMpdCommand status Jul 08 14:22:12 volumio-1 volumio[895]: info: sendMpdCommand status took 2 milliseconds Jul 08 14:22:12 volumio-1 volumio[895]: verbose: ControllerMpd::parseState Jul 08 14:22:12 volumio-1 volumio[895]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 14:22:12 volumio-1 volumio[895]: info: sendMpdCommand status took 2 milliseconds Jul 08 14:22:12 volumio-1 volumio[895]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 08 14:22:12 volumio-1 volumio[895]: verbose: ControllerMpd::parseState Jul 08 14:22:12 volumio-1 volumio[895]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 14:22:12 volumio-1 volumio[895]: verbose: ControllerMpd::parseTrackInfo Jul 08 14:22:12 volumio-1 volumio[895]: info: ControllerMpd::pushState Jul 08 14:22:12 volumio-1 volumio[895]: info: CoreCommandRouter::servicePushState Jul 08 14:22:12 volumio-1 volumio[895]: verbose: In UPNP mode Jul 08 14:22:12 volumio-1 volumio[895]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":129,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"客官不可以","artist":"徐良/小凌","album":null,"uri":"http://aqqmusic.tc.qq.com/M500002BdwH91oJ1A1.mp3?guid=00000000220dc7310000018e057bcdd7&vkey=09DF3D48CBEC60D6962073FF1995F42BAF2FDF7188E62AEF9373758008112E6FDFA07F4B2FBE234F52B37128397B95CCECE3C33A52F57BAE&uin=335238641&redirect=1&fromtag=111042","trackType":""} Jul 08 14:22:12 volumio-1 volumio[895]: verbose: CURRENT POSITION 0 Jul 08 14:22:12 volumio-1 volumio[895]: info: CoreStateMachine::syncState stateService play Jul 08 14:22:12 volumio-1 volumio[895]: info: CoreStateMachine::syncState currentStatus stop Jul 08 14:22:12 volumio-1 volumio[895]: info: ------------------------------ 11ms Jul 08 14:22:12 volumio-1 volumio[895]: info: Jul 08 14:22:12 volumio-1 volumio[895]: ---------------------------- MPD announces system playlist update Jul 08 14:22:12 volumio-1 volumio[895]: info: Ignoring MPD Status Update Jul 08 14:22:12 volumio-1 volumio[895]: info: Jul 08 14:22:12 volumio-1 volumio[895]: ---------------------------- MPD announces state update: player Jul 08 14:22:12 volumio-1 volumio[895]: info: ControllerMpd::getState Jul 08 14:22:12 volumio-1 volumio[895]: verbose: ControllerMpd::sendMpdCommand status Jul 08 14:22:12 volumio-1 volumio[895]: info: sendMpdCommand playlistinfo took 7 milliseconds Jul 08 14:22:12 volumio-1 volumio[895]: verbose: ControllerMpd::parseTrackInfo Jul 08 14:22:12 volumio-1 volumio[895]: info: ControllerMpd::pushState Jul 08 14:22:12 volumio-1 volumio[895]: info: CoreCommandRouter::servicePushState Jul 08 14:22:12 volumio-1 volumio[895]: verbose: In UPNP mode Jul 08 14:22:12 volumio-1 volumio[895]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":129,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"客官不可以 (不完整)","artist":"徐良","album":null,"uri":"http://aqqmusic.tc.qq.com/M500002BdwH91oJ1A1.mp3?guid=00000000220dc7310000018e057bcdd7&vkey=09DF3D48CBEC60D6962073FF1995F42BAF2FDF7188E62AEF9373758008112E6FDFA07F4B2FBE234F52B37128397B95CCECE3C33A52F57BAE&uin=335238641&redirect=1&fromtag=111042","trackType":""} Jul 08 14:22:12 volumio-1 volumio[895]: verbose: CURRENT POSITION 0 Jul 08 14:22:12 volumio-1 volumio[895]: info: CoreStateMachine::syncState stateService play Jul 08 14:22:12 volumio-1 volumio[895]: info: CoreStateMachine::syncState currentStatus play Jul 08 14:22:12 volumio-1 volumio[895]: info: Received an update from plugin. extracting info from payload Jul 08 14:22:12 volumio-1 volumio[895]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 08 14:22:12 volumio-1 volumio[895]: info: CoreStateMachine::pushState Jul 08 14:22:12 volumio-1 volumio[895]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 14:22:12 volumio-1 volumio[895]: info: CoreCommandRouter::volumioPushState Jul 08 14:22:12 volumio-1 volumio[895]: info: CoreStateMachine::pushState Jul 08 14:22:12 volumio-1 volumio[895]: info: CoreCommandRouter::volumioPushState Jul 08 14:22:12 volumio-1 volumio[895]: info: ------------------------------ 34ms Jul 08 14:22:12 volumio-1 volumio[895]: info: ------------------------------ 25ms Jul 08 14:22:12 volumio-1 volumio[895]: info: sendMpdCommand status took 23 milliseconds Jul 08 14:22:12 volumio-1 volumio[895]: verbose: ControllerMpd::parseState Jul 08 14:22:12 volumio-1 volumio[895]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 14:22:12 volumio-1 volumio[895]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 08 14:22:12 volumio-1 volumio[895]: verbose: ControllerMpd::parseTrackInfo Jul 08 14:22:12 volumio-1 volumio[895]: info: ControllerMpd::pushState Jul 08 14:22:12 volumio-1 volumio[895]: info: CoreCommandRouter::servicePushState Jul 08 14:22:12 volumio-1 volumio[895]: verbose: In UPNP mode Jul 08 14:22:12 volumio-1 volumio[895]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":129,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"客官不可以 (不完整)","artist":"徐良","album":null,"uri":"http://aqqmusic.tc.qq.com/M500002BdwH91oJ1A1.mp3?guid=00000000220dc7310000018e057bcdd7&vkey=09DF3D48CBEC60D6962073FF1995F42BAF2FDF7188E62AEF9373758008112E6FDFA07F4B2FBE234F52B37128397B95CCECE3C33A52F57BAE&uin=335238641&redirect=1&fromtag=111042","trackType":""} Jul 08 14:22:12 volumio-1 volumio[895]: verbose: CURRENT POSITION 0 Jul 08 14:22:12 volumio-1 volumio[895]: info: CoreStateMachine::syncState stateService play Jul 08 14:22:12 volumio-1 volumio[895]: info: CoreStateMachine::syncState currentStatus play Jul 08 14:22:12 volumio-1 volumio[895]: info: Received an update from plugin. extracting info from payload Jul 08 14:22:12 volumio-1 volumio[895]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 08 14:22:12 volumio-1 volumio[895]: info: CoreStateMachine::pushState Jul 08 14:22:12 volumio-1 volumio[895]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 14:22:12 volumio-1 volumio[895]: info: CoreCommandRouter::volumioPushState Jul 08 14:22:12 volumio-1 volumio[895]: info: CoreStateMachine::pushState Jul 08 14:22:12 volumio-1 volumio[895]: info: CoreCommandRouter::volumioPushState Jul 08 14:22:12 volumio-1 volumio[895]: info: ------------------------------ 40ms Jul 08 14:22:12 volumio-1 volumio[895]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%BE%90%E8%89%AF/560006c4-c02f-4a16-802d-f21116de9a37.jpg' Jul 08 14:22:12 volumio-1 volumio[895]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%BE%90%E8%89%AF/560006c4-c02f-4a16-802d-f21116de9a37.jpg' Jul 08 14:22:19 volumio-1 volumio[895]: info: Jul 08 14:22:19 volumio-1 volumio[895]: ---------------------------- MPD announces state update: player Jul 08 14:22:19 volumio-1 volumio[895]: info: ControllerMpd::getState Jul 08 14:22:19 volumio-1 volumio[895]: verbose: ControllerMpd::sendMpdCommand status Jul 08 14:22:19 volumio-1 volumio[895]: info: sendMpdCommand status took 0 milliseconds Jul 08 14:22:19 volumio-1 volumio[895]: verbose: ControllerMpd::parseState Jul 08 14:22:19 volumio-1 volumio[895]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 14:22:19 volumio-1 volumio[895]: info: sendMpdCommand playlistinfo took 0 milliseconds Jul 08 14:22:19 volumio-1 volumio[895]: verbose: ControllerMpd::parseTrackInfo Jul 08 14:22:19 volumio-1 volumio[895]: info: ControllerMpd::pushState Jul 08 14:22:19 volumio-1 volumio[895]: info: CoreCommandRouter::servicePushState Jul 08 14:22:19 volumio-1 volumio[895]: info: CoreStateMachine::pushState Jul 08 14:22:19 volumio-1 volumio[895]: info: CoreCommandRouter::volumioPushState Jul 08 14:22:19 volumio-1 volumio[895]: info: CorePlayQueue::getTrack 0 Jul 08 14:22:19 volumio-1 volumio[895]: 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":null,"uri":"http://aqqmusic.tc.qq.com/M500002BdwH91oJ1A1.mp3?guid=00000000220dc7310000018e057bcdd7&vkey=09DF3D48CBEC60D6962073FF1995F42BAF2FDF7188E62AEF9373758008112E6FDFA07F4B2FBE234F52B37128397B95CCECE3C33A52F57BAE&uin=335238641&redirect=1&fromtag=111042","trackType":""} Jul 08 14:22:19 volumio-1 volumio[895]: verbose: CURRENT POSITION 0 Jul 08 14:22:19 volumio-1 volumio[895]: info: CoreStateMachine::syncState stateService stop Jul 08 14:22:19 volumio-1 volumio[895]: info: CoreStateMachine::syncState currentStatus play Jul 08 14:22:19 volumio-1 volumio[895]: info: CoreCommandRouter::volumioPushState Jul 08 14:22:19 volumio-1 volumio[895]: info: CoreStateMachine::stPlaybackTimer Jul 08 14:22:19 volumio-1 volumio[895]: info: ------------------------------ 14ms Jul 08 14:22:19 volumio-1 volumio[895]: info: Clearing queue after UPNP request Jul 08 14:22:19 volumio-1 volumio[895]: info: CoreStateMachine::ClearQueue Jul 08 14:22:19 volumio-1 volumio[895]: info: CoreStateMachine::stop Jul 08 14:22:19 volumio-1 volumio[895]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 08 14:22:19 volumio-1 volumio[895]: info: CorePlayQueue::clearPlayQueue Jul 08 14:22:19 volumio-1 volumio[895]: info: CorePlayQueue::saveQueue Jul 08 14:22:19 volumio-1 volumio[895]: info: CoreCommandRouter::volumioPushState Jul 08 14:22:19 volumio-1 volumio[895]: info: CoreCommandRouter::volumioPushQueue Jul 08 14:22:19 volumio-1 volumio[895]: info: Jul 08 14:22:19 volumio-1 volumio[895]: ---------------------------- MPD announces system playlist update Jul 08 14:22:19 volumio-1 volumio[895]: info: Ignoring MPD Status Update Jul 08 14:22:19 volumio-1 volumio[895]: error: updateQueue error: null Jul 08 14:22:19 volumio-1 volumio[895]: info: ------------------------------ 1ms Jul 08 14:22:19 volumio-1 volumio[895]: info: Starting UPNP Playback Jul 08 14:22:19 volumio-1 volumio[895]: info: Preparing playback through UPNP Jul 08 14:22:19 volumio-1 volumio[895]: info: CoreCommandRouter::volumioGetState Jul 08 14:22:19 volumio-1 volumio[895]: info: CorePlayQueue::getTrack 0 Jul 08 14:22:19 volumio-1 volumio[895]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 08 14:22:20 volumio-1 volumio[895]: info: Jul 08 14:22:20 volumio-1 volumio[895]: ---------------------------- MPD announces system playlist update Jul 08 14:22:20 volumio-1 volumio[895]: info: Ignoring MPD Status Update Jul 08 14:22:20 volumio-1 volumio[895]: info: Jul 08 14:22:20 volumio-1 volumio[895]: ---------------------------- MPD announces system playlist update Jul 08 14:22:20 volumio-1 volumio[895]: info: Ignoring MPD Status Update Jul 08 14:22:20 volumio-1 volumio[895]: info: ------------------------------ 2ms Jul 08 14:22:20 volumio-1 volumio[895]: info: ------------------------------ 2ms Jul 08 14:22:20 volumio-1 volumio[895]: info: Jul 08 14:22:20 volumio-1 volumio[895]: ---------------------------- MPD announces system playlist update Jul 08 14:22:20 volumio-1 volumio[895]: info: Ignoring MPD Status Update Jul 08 14:22:20 volumio-1 volumio[895]: info: Jul 08 14:22:20 volumio-1 volumio[895]: ---------------------------- MPD announces system playlist update Jul 08 14:22:20 volumio-1 volumio[895]: info: Ignoring MPD Status Update Jul 08 14:22:20 volumio-1 volumio[895]: info: ------------------------------ 1ms Jul 08 14:22:20 volumio-1 volumio[895]: info: ------------------------------ 1ms Jul 08 14:22:20 volumio-1 volumio[895]: info: Jul 08 14:22:20 volumio-1 volumio[895]: ---------------------------- MPD announces system playlist update Jul 08 14:22:20 volumio-1 volumio[895]: info: Ignoring MPD Status Update Jul 08 14:22:20 volumio-1 volumio[895]: info: ------------------------------ 1ms Jul 08 14:22:20 volumio-1 volumio[895]: info: Jul 08 14:22:20 volumio-1 volumio[895]: ---------------------------- MPD announces system playlist update Jul 08 14:22:20 volumio-1 volumio[895]: info: Ignoring MPD Status Update Jul 08 14:22:20 volumio-1 volumio[895]: info: ------------------------------ 1ms Jul 08 14:22:20 volumio-1 volumio[895]: info: Jul 08 14:22:20 volumio-1 volumio[895]: ---------------------------- MPD announces state update: player Jul 08 14:22:20 volumio-1 volumio[895]: info: ControllerMpd::getState Jul 08 14:22:20 volumio-1 volumio[895]: verbose: ControllerMpd::sendMpdCommand status Jul 08 14:22:20 volumio-1 volumio[895]: info: Jul 08 14:22:20 volumio-1 volumio[895]: ---------------------------- MPD announces state update: player Jul 08 14:22:20 volumio-1 volumio[895]: info: ControllerMpd::getState Jul 08 14:22:20 volumio-1 volumio[895]: verbose: ControllerMpd::sendMpdCommand status Jul 08 14:22:20 volumio-1 volumio[895]: info: sendMpdCommand status took 1 milliseconds Jul 08 14:22:20 volumio-1 volumio[895]: verbose: ControllerMpd::parseState Jul 08 14:22:20 volumio-1 volumio[895]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 14:22:20 volumio-1 volumio[895]: info: sendMpdCommand status took 2 milliseconds Jul 08 14:22:20 volumio-1 volumio[895]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 08 14:22:20 volumio-1 volumio[895]: verbose: ControllerMpd::parseState Jul 08 14:22:20 volumio-1 volumio[895]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 14:22:20 volumio-1 volumio[895]: verbose: ControllerMpd::parseTrackInfo Jul 08 14:22:20 volumio-1 volumio[895]: info: ControllerMpd::pushState Jul 08 14:22:20 volumio-1 volumio[895]: info: CoreCommandRouter::servicePushState Jul 08 14:22:20 volumio-1 volumio[895]: verbose: In UPNP mode Jul 08 14:22:20 volumio-1 volumio[895]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":233,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"第一次爱的人","artist":"王心凌","album":"爱你","uri":"http://aqqmusic.tc.qq.com/F000001SvSoe4CqmPv.flac?guid=00000000220dc7310000018e057bcdd7&vkey=274E67696E26AAB14FB8B36FD30ED81E28C8A8578457456BEE095FCBB9D99D200A48593B98990537365D9331A6123C89DA885E9D5C356103&uin=335238641&src=F000000kOQoN042D3Q.flac&redirect=1&fromtag=111114","trackType":""} Jul 08 14:22:20 volumio-1 volumio[895]: verbose: CURRENT POSITION 0 Jul 08 14:22:20 volumio-1 volumio[895]: info: CoreStateMachine::syncState stateService play Jul 08 14:22:20 volumio-1 volumio[895]: info: CoreStateMachine::syncState currentStatus stop Jul 08 14:22:20 volumio-1 volumio[895]: info: ------------------------------ 10ms Jul 08 14:22:20 volumio-1 volumio[895]: info: sendMpdCommand playlistinfo took 6 milliseconds Jul 08 14:22:20 volumio-1 volumio[895]: verbose: ControllerMpd::parseTrackInfo Jul 08 14:22:20 volumio-1 volumio[895]: info: ControllerMpd::pushState Jul 08 14:22:20 volumio-1 volumio[895]: info: CoreCommandRouter::servicePushState Jul 08 14:22:20 volumio-1 volumio[895]: verbose: In UPNP mode Jul 08 14:22:20 volumio-1 volumio[895]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":233,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"第一次爱的人","artist":"王心凌","album":"爱你","uri":"http://aqqmusic.tc.qq.com/F000001SvSoe4CqmPv.flac?guid=00000000220dc7310000018e057bcdd7&vkey=274E67696E26AAB14FB8B36FD30ED81E28C8A8578457456BEE095FCBB9D99D200A48593B98990537365D9331A6123C89DA885E9D5C356103&uin=335238641&src=F000000kOQoN042D3Q.flac&redirect=1&fromtag=111114","trackType":""} Jul 08 14:22:20 volumio-1 volumio[895]: verbose: CURRENT POSITION 0 Jul 08 14:22:20 volumio-1 volumio[895]: info: CoreStateMachine::syncState stateService play Jul 08 14:22:20 volumio-1 volumio[895]: info: CoreStateMachine::syncState currentStatus play Jul 08 14:22:20 volumio-1 volumio[895]: info: Received an update from plugin. extracting info from payload Jul 08 14:22:20 volumio-1 volumio[895]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 08 14:22:20 volumio-1 volumio[895]: info: CoreStateMachine::pushState Jul 08 14:22:20 volumio-1 volumio[895]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 14:22:20 volumio-1 volumio[895]: info: CoreCommandRouter::volumioPushState Jul 08 14:22:20 volumio-1 volumio[895]: info: CoreStateMachine::pushState Jul 08 14:22:20 volumio-1 volumio[895]: info: CoreCommandRouter::volumioPushState Jul 08 14:22:20 volumio-1 volumio[895]: info: ------------------------------ 37ms Jul 08 14:22:20 volumio-1 volumio[895]: info: Jul 08 14:22:20 volumio-1 volumio[895]: ---------------------------- MPD announces system playlist update Jul 08 14:22:20 volumio-1 volumio[895]: info: Ignoring MPD Status Update Jul 08 14:22:20 volumio-1 volumio[895]: info: CoreCommandRouter::volumioGetQueue Jul 08 14:22:20 volumio-1 volumio[895]: info: CoreStateMachine::getQueue Jul 08 14:22:20 volumio-1 volumio[895]: info: CorePlayQueue::getQueue Jul 08 14:22:20 volumio-1 volumio[895]: info: CoreCommandRouter::volumioGetQueue Jul 08 14:22:20 volumio-1 volumio[895]: info: CoreStateMachine::getQueue Jul 08 14:22:20 volumio-1 volumio[895]: info: CorePlayQueue::getQueue Jul 08 14:22:20 volumio-1 volumio[895]: info: Jul 08 14:22:20 volumio-1 volumio[895]: ---------------------------- MPD announces state update: player Jul 08 14:22:20 volumio-1 volumio[895]: info: ControllerMpd::getState Jul 08 14:22:20 volumio-1 volumio[895]: verbose: ControllerMpd::sendMpdCommand status Jul 08 14:22:20 volumio-1 volumio[895]: info: ------------------------------ 6ms Jul 08 14:22:20 volumio-1 volumio[895]: info: sendMpdCommand status took 1 milliseconds Jul 08 14:22:20 volumio-1 volumio[895]: verbose: ControllerMpd::parseState Jul 08 14:22:20 volumio-1 volumio[895]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 14:22:20 volumio-1 volumio[895]: info: sendMpdCommand playlistinfo took 0 milliseconds Jul 08 14:22:20 volumio-1 volumio[895]: verbose: ControllerMpd::parseTrackInfo Jul 08 14:22:20 volumio-1 volumio[895]: info: ControllerMpd::pushState Jul 08 14:22:20 volumio-1 volumio[895]: info: CoreCommandRouter::servicePushState Jul 08 14:22:20 volumio-1 volumio[895]: verbose: In UPNP mode Jul 08 14:22:20 volumio-1 volumio[895]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":233,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"774 Kbps","isStreaming":false,"title":"第一次爱的人","artist":"王心凌","album":"爱你","uri":"http://aqqmusic.tc.qq.com/F000001SvSoe4CqmPv.flac?guid=00000000220dc7310000018e057bcdd7&vkey=274E67696E26AAB14FB8B36FD30ED81E28C8A8578457456BEE095FCBB9D99D200A48593B98990537365D9331A6123C89DA885E9D5C356103&uin=335238641&src=F000000kOQoN042D3Q.flac&redirect=1&fromtag=111114","trackType":""} Jul 08 14:22:20 volumio-1 volumio[895]: verbose: CURRENT POSITION 0 Jul 08 14:22:20 volumio-1 volumio[895]: info: CoreStateMachine::syncState stateService play Jul 08 14:22:20 volumio-1 volumio[895]: info: CoreStateMachine::syncState currentStatus play Jul 08 14:22:20 volumio-1 volumio[895]: info: Received an update from plugin. extracting info from payload Jul 08 14:22:20 volumio-1 volumio[895]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 08 14:22:20 volumio-1 volumio[895]: info: CoreStateMachine::pushState Jul 08 14:22:20 volumio-1 volumio[895]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 14:22:20 volumio-1 volumio[895]: info: CoreCommandRouter::volumioPushState Jul 08 14:22:20 volumio-1 volumio[895]: info: CoreStateMachine::pushState Jul 08 14:22:20 volumio-1 volumio[895]: info: CoreCommandRouter::volumioPushState Jul 08 14:22:20 volumio-1 volumio[895]: info: ------------------------------ 21ms Jul 08 14:22:20 volumio-1 volumio[895]: info: CoreCommandRouter::volumioGetQueue Jul 08 14:22:20 volumio-1 volumio[895]: info: CoreStateMachine::getQueue Jul 08 14:22:20 volumio-1 volumio[895]: info: CorePlayQueue::getQueue Jul 08 14:22:20 volumio-1 volumio[895]: info: CoreCommandRouter::volumioGetQueue Jul 08 14:22:20 volumio-1 volumio[895]: info: CoreStateMachine::getQueue Jul 08 14:22:20 volumio-1 volumio[895]: info: CorePlayQueue::getQueue Jul 08 14:22:20 volumio-1 volumio[895]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 08 14:22:20 volumio-1 volumio[895]: Error: connect ETIMEDOUT 128.242.240.91:80 Jul 08 14:22:20 volumio-1 volumio[895]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Jul 08 14:22:20 volumio-1 volumio[895]: errno: -110, Jul 08 14:22:20 volumio-1 volumio[895]: code: 'ETIMEDOUT', Jul 08 14:22:20 volumio-1 volumio[895]: syscall: 'connect', Jul 08 14:22:20 volumio-1 volumio[895]: address: '128.242.240.91', Jul 08 14:22:20 volumio-1 volumio[895]: port: 80 Jul 08 14:22:20 volumio-1 volumio[895]: } Jul 08 14:22:20 volumio-1 volumio[895]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 08 14:22:21 volumio-1 sudo[3361]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-08 14:21 Jul 08 14:22:21 volumio-1 sudo[3361]: 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="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"