-- Logs begin at Fri 2025-05-02 20:43:16 UTC, end at Fri 2025-05-02 20:53:08 UTC. -- May 02 20:52:17 volumio kernel: usb 4-1: USB disconnect, device number 3 May 02 20:52:17 volumio volumio[839]: info: May 02 20:52:17 volumio volumio[839]: ---------------------------- MPD announces state update: player May 02 20:52:17 volumio volumio[839]: info: ControllerMpd::getState May 02 20:52:17 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand status May 02 20:52:17 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand clearerror May 02 20:52:17 volumio volumio[839]: verbose: ControllerMpd::parseState May 02 20:52:17 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 20:52:17 volumio volumio[839]: verbose: ControllerMpd::parseTrackInfo May 02 20:52:17 volumio volumio[839]: info: ControllerMpd::pushState May 02 20:52:17 volumio volumio[839]: info: CoreCommandRouter::servicePushState May 02 20:52:17 volumio volumio[839]: info: CorePlayQueue::getTrack 198 May 02 20:52:17 volumio volumio[839]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":42881,"duration":247,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2822 Kbps","isStreaming":false,"title":"阿梨.粤 - 最爱 - 你的浅笑.dff","artist":null,"album":null,"uri":"INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 你的浅笑.dff","trackType":"dff"} May 02 20:52:17 volumio volumio[839]: verbose: CURRENT POSITION 198 May 02 20:52:17 volumio volumio[839]: info: CoreStateMachine::syncState stateService pause May 02 20:52:17 volumio volumio[839]: info: CoreStateMachine::syncState currentStatus play May 02 20:52:17 volumio volumio[839]: info: CoreStateMachine::pushState May 02 20:52:17 volumio volumio[839]: info: CorePlayQueue::getTrack 198 May 02 20:52:17 volumio volumio[839]: info: CoreCommandRouter::volumioPushState May 02 20:52:17 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 20:52:17 volumio volumio[839]: info: ------------------------------ 16ms May 02 20:52:17 volumio volumio[839]: info: No valid Plugin REST Endpoint May 02 20:52:17 volumio volumio[839]: info: May 02 20:52:17 volumio volumio[839]: ---------------------------- USB Audio Device Detached May 02 20:52:17 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach May 02 20:52:17 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 02 20:52:17 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: raat , onStop May 02 20:52:19 volumio kernel: usb 4-1: new full-speed USB device number 4 using uhci_hcd May 02 20:52:19 volumio kernel: usb 4-1: New USB device found, idVendor=0c76, idProduct=1665 May 02 20:52:19 volumio kernel: usb 4-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 May 02 20:52:19 volumio kernel: usb 4-1: Product: GS3 May 02 20:52:19 volumio kernel: usb 4-1: Manufacturer: PLEXTONE Co.,Ltd. May 02 20:52:19 volumio kernel: usb 4-1: SerialNumber: 000000000000 May 02 20:52:19 volumio kernel: usb 4-1: Warning! Unlikely big volume range (=1008), cval->res is probably wrong. May 02 20:52:19 volumio kernel: usb 4-1: [49] FU [PCM Playback Volume] ch = 2, val = -16129/-1/16 May 02 20:52:19 volumio kernel: usb 4-1: Warning! Unlikely big volume range (=496), cval->res is probably wrong. May 02 20:52:19 volumio kernel: usb 4-1: [50] FU [Mic Capture Volume] ch = 1, val = 0/7936/16 May 02 20:52:19 volumio kernel: input: PLEXTONE Co.,Ltd. GS3 as /devices/pci0000:00/0000:00:1a.1/usb4/4-1/4-1:1.3/0003:0C76:1665.0005/input/input23 May 02 20:52:19 volumio kernel: hid-generic 0003:0C76:1665.0005: input,hidraw2: USB HID v1.00 Device [PLEXTONE Co.,Ltd. GS3] on usb-0000:00:1a.1-1/input3 May 02 20:52:19 volumio volumio[839]: info: May 02 20:52:19 volumio volumio[839]: ---------------------------- USB Audio Device Attached May 02 20:52:19 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach May 02 20:52:19 volumio volumio[839]: info: CoreCommandRouter::Close All Modals sent May 02 20:52:19 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 02 20:52:19 volumio volumio[839]: info: Output device has changed, restarting MPD May 02 20:52:19 volumio volumio[839]: info: Output device has changed, restarting Shairport Sync May 02 20:52:19 volumio sudo[2739]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 02 20:52:19 volumio sudo[2739]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 20:52:19 volumio sudo[2741]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 02 20:52:19 volumio sudo[2739]: pam_unix(sudo:session): session closed for user root May 02 20:52:19 volumio sudo[2741]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 20:52:19 volumio systemd[1]: Stopping Music Player Daemon... May 02 20:52:19 volumio volumio[839]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 02 20:52:19 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 02 20:52:19 volumio systemd[1]: Starting Music Player Daemon... May 02 20:52:19 volumio volumio[839]: info: Setting mixer PCM for card GS3 May 02 20:52:19 volumio volumio[839]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 02 20:52:19 volumio volumio[839]: info: Updating Volume Controller Parameters: Device: 5 Name: Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 10 May 02 20:52:19 volumio volumio[839]: info: CoreCommandRouter::getUIConfigOnPlugin May 02 20:52:19 volumio volumio[839]: info: MPD Permissions set May 02 20:52:19 volumio volumio[839]: info: No valid Plugin REST Endpoint May 02 20:52:19 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 20:52:19 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 20:52:19 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 20:52:19 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 20:52:19 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 20:52:19 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 20:52:19 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 20:52:19 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 02 20:52:19 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 02 20:52:19 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 20:52:19 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 02 20:52:19 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 02 20:52:19 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 02 20:52:19 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 02 20:52:19 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 02 20:52:19 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 02 20:52:19 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 02 20:52:19 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 02 20:52:19 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 02 20:52:19 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 02 20:52:19 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 02 20:52:19 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 02 20:52:19 volumio volumio[839]: info: Starting Shairport Sync May 02 20:52:19 volumio volumio[839]: info: VolumeController:: Volume=41 Mute =false May 02 20:52:19 volumio volumio[839]: info: CoreStateMachine::pushState May 02 20:52:19 volumio volumio[839]: info: CorePlayQueue::getTrack 198 May 02 20:52:19 volumio volumio[839]: info: CoreCommandRouter::volumioPushState May 02 20:52:19 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 20:52:19 volumio sudo[2767]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 02 20:52:19 volumio sudo[2767]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 20:52:19 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 02 20:52:19 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... May 02 20:52:19 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 02 20:52:19 volumio sudo[2767]: pam_unix(sudo:session): session closed for user root May 02 20:52:19 volumio volumio[839]: info: Shairport-Sync Started May 02 20:52:19 volumio systemd[1]: Started Music Player Daemon. May 02 20:52:19 volumio sudo[2741]: pam_unix(sudo:session): session closed for user root May 02 20:52:20 volumio volumio[839]: error: Upnp client error: Error: This socket has been ended by the other party May 02 20:52:20 volumio volumio[839]: error: updateQueue error: null May 02 20:52:20 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat May 02 20:52:22 volumio volumio[839]: info: CoreCommandRouter::volumioRetrieveVolumeLevels May 02 20:52:22 volumio volumio[839]: info: CoreStateMachine::getcurrentVolume May 02 20:52:22 volumio volumio[839]: info: CoreCommandRouter::volumioRetrievevolume May 02 20:52:22 volumio volumio[839]: info: VolumeController:: Volume=41 Mute =false May 02 20:52:22 volumio volumio[839]: info: CoreStateMachine::pushState May 02 20:52:22 volumio volumio[839]: info: CorePlayQueue::getTrack 198 May 02 20:52:22 volumio volumio[839]: info: CoreCommandRouter::volumioPushState May 02 20:52:22 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 20:52:22 volumio volumio[839]: info: CoreStateMachine::updateTrackBlock May 02 20:52:22 volumio volumio[839]: info: CorePlayQueue::getTrackBlock May 02 20:52:22 volumio volumio[839]: info: CoreCommandRouter::volumioRetrievevolume May 02 20:52:22 volumio volumio[839]: info: VolumeController:: Volume=41 Mute =false May 02 20:52:22 volumio volumio[839]: info: CoreStateMachine::pushState May 02 20:52:22 volumio volumio[839]: info: CorePlayQueue::getTrack 198 May 02 20:52:22 volumio volumio[839]: info: CoreCommandRouter::volumioPushState May 02 20:52:22 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 20:52:27 volumio volumio[839]: info: CoreCommandRouter::volumioNext May 02 20:52:27 volumio volumio[839]: info: CoreStateMachine::next May 02 20:52:27 volumio volumio[839]: info: CoreStateMachine::stop May 02 20:52:27 volumio volumio[839]: info: CoreStateMachine::setConsumeUpdateService undefined May 02 20:52:27 volumio volumio[839]: UNSET VOLATILE May 02 20:52:27 volumio volumio[839]: info: CoreStateMachine::stPlaybackTimer May 02 20:52:27 volumio volumio[839]: info: CoreStateMachine::updateTrackBlock May 02 20:52:27 volumio volumio[839]: info: CorePlayQueue::getTrackBlock May 02 20:52:27 volumio volumio[839]: info: CoreStateMachine::pushState May 02 20:52:27 volumio volumio[839]: info: CorePlayQueue::getTrack 198 May 02 20:52:27 volumio volumio[839]: info: CoreCommandRouter::volumioPushState May 02 20:52:27 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 20:52:27 volumio volumio[839]: info: CoreStateMachine::serviceStop May 02 20:52:27 volumio volumio[839]: info: CorePlayQueue::getTrack 198 May 02 20:52:27 volumio volumio[839]: info: CoreCommandRouter::serviceStop May 02 20:52:27 volumio volumio[839]: info: ControllerMpd::stop May 02 20:52:27 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand stop May 02 20:52:27 volumio volumio[839]: info: CoreStateMachine::play index undefined May 02 20:52:27 volumio volumio[839]: info: CoreStateMachine::setConsumeUpdateService undefined May 02 20:52:27 volumio volumio[839]: info: CorePlayQueue::getTrack 3 May 02 20:52:27 volumio volumio[839]: info: CoreStateMachine::startPlaybackTimer May 02 20:52:27 volumio volumio[839]: info: CorePlayQueue::getTrack 3 May 02 20:52:27 volumio volumio[839]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/song/AG-No Expectations.flac May 02 20:52:27 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand stop May 02 20:52:27 volumio volumio[839]: info: CoreStateMachine::updateTrackBlock May 02 20:52:27 volumio volumio[839]: info: CorePlayQueue::getTrackBlock May 02 20:52:27 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand clear May 02 20:52:27 volumio volumio[839]: info: May 02 20:52:27 volumio volumio[839]: ---------------------------- MPD announces system playlist update May 02 20:52:27 volumio volumio[839]: info: Ignoring MPD Status Update May 02 20:52:27 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/song/AG-No Expectations.flac" May 02 20:52:27 volumio volumio[839]: error: updateQueue error: null May 02 20:52:27 volumio volumio[839]: info: May 02 20:52:27 volumio volumio[839]: ---------------------------- MPD announces system playlist update May 02 20:52:27 volumio volumio[839]: info: Ignoring MPD Status Update May 02 20:52:27 volumio volumio[839]: info: ------------------------------ 1ms May 02 20:52:27 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand play May 02 20:52:27 volumio volumio[839]: info: ------------------------------ 1ms May 02 20:52:27 volumio volumio[839]: info: May 02 20:52:27 volumio volumio[839]: ---------------------------- MPD announces state update: player May 02 20:52:27 volumio volumio[839]: info: ControllerMpd::getState May 02 20:52:27 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand status May 02 20:52:27 volumio volumio[839]: info: May 02 20:52:27 volumio volumio[839]: ---------------------------- MPD announces state update: player May 02 20:52:27 volumio volumio[839]: info: ControllerMpd::getState May 02 20:52:27 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand status May 02 20:52:27 volumio volumio[839]: verbose: ControllerMpd::parseState May 02 20:52:27 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 20:52:27 volumio volumio[839]: verbose: ControllerMpd::parseState May 02 20:52:27 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 20:52:27 volumio volumio[839]: verbose: ControllerMpd::parseTrackInfo May 02 20:52:27 volumio volumio[839]: info: ControllerMpd::pushState May 02 20:52:27 volumio volumio[839]: info: CoreCommandRouter::servicePushState May 02 20:52:27 volumio volumio[839]: info: CorePlayQueue::getTrack 3 May 02 20:52:27 volumio volumio[839]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":288,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"466 Kbps","isStreaming":false,"title":"No Expectations","artist":"AG","album":"Discover: Songs Of The Rolling Stones Vol. 2","uri":"INTERNAL/song/AG-No Expectations.flac","trackType":"flac"} May 02 20:52:27 volumio volumio[839]: verbose: CURRENT POSITION 3 May 02 20:52:27 volumio volumio[839]: info: CoreStateMachine::syncState stateService play May 02 20:52:27 volumio volumio[839]: info: CoreStateMachine::syncState currentStatus stop May 02 20:52:27 volumio volumio[839]: info: CoreStateMachine::pushState May 02 20:52:27 volumio volumio[839]: info: CorePlayQueue::getTrack 3 May 02 20:52:27 volumio volumio[839]: info: CoreCommandRouter::volumioPushState May 02 20:52:27 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 20:52:27 volumio volumio[839]: info: ------------------------------ 19ms May 02 20:52:27 volumio volumio[839]: verbose: ControllerMpd::parseTrackInfo May 02 20:52:27 volumio volumio[839]: info: ControllerMpd::pushState May 02 20:52:27 volumio volumio[839]: info: CoreCommandRouter::servicePushState May 02 20:52:27 volumio volumio[839]: info: CorePlayQueue::getTrack 3 May 02 20:52:27 volumio volumio[839]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":288,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"466 Kbps","isStreaming":false,"title":"No Expectations","artist":"AG","album":"Discover: Songs Of The Rolling Stones Vol. 2","uri":"INTERNAL/song/AG-No Expectations.flac","trackType":"flac"} May 02 20:52:27 volumio volumio[839]: verbose: CURRENT POSITION 3 May 02 20:52:27 volumio volumio[839]: info: CoreStateMachine::syncState stateService play May 02 20:52:27 volumio volumio[839]: info: CoreStateMachine::syncState currentStatus play May 02 20:52:27 volumio volumio[839]: info: Received an update from plugin. extracting info from payload May 02 20:52:27 volumio volumio[839]: info: CoreStateMachine::pushState May 02 20:52:27 volumio volumio[839]: info: CorePlayQueue::getTrack 3 May 02 20:52:27 volumio volumio[839]: info: CoreCommandRouter::volumioPushState May 02 20:52:27 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 20:52:27 volumio volumio[839]: info: CoreStateMachine::pushState May 02 20:52:27 volumio volumio[839]: info: CorePlayQueue::getTrack 3 May 02 20:52:27 volumio volumio[839]: info: CoreCommandRouter::volumioPushState May 02 20:52:27 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 20:52:27 volumio volumio[839]: info: ------------------------------ 41ms May 02 20:52:27 volumio volumio[839]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/AG/Discover%3A%20Songs%20Of%20The%20Rolling%20Stones%20Vol.%202/e58ea61b-3eb8-4b8e-8326-e78858325d2a.jpg' May 02 20:52:34 volumio volumio[839]: info: VolumeController::SetAlsaVolume40 May 02 20:52:34 volumio volumio[839]: info: CoreStateMachine::pushState May 02 20:52:34 volumio volumio[839]: info: CorePlayQueue::getTrack 3 May 02 20:52:34 volumio volumio[839]: info: CoreCommandRouter::volumioPushState May 02 20:52:34 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 20:52:34 volumio volumio[839]: info: VolumeController::SetAlsaVolume35 May 02 20:52:34 volumio volumio[839]: info: CoreStateMachine::pushState May 02 20:52:34 volumio volumio[839]: info: CorePlayQueue::getTrack 3 May 02 20:52:34 volumio volumio[839]: info: CoreCommandRouter::volumioPushState May 02 20:52:34 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 20:52:36 volumio volumio[839]: info: CoreCommandRouter::volumioNext May 02 20:52:36 volumio volumio[839]: info: CoreStateMachine::next May 02 20:52:36 volumio volumio[839]: info: CoreStateMachine::stop May 02 20:52:36 volumio volumio[839]: info: CoreStateMachine::setConsumeUpdateService undefined May 02 20:52:36 volumio volumio[839]: UNSET VOLATILE May 02 20:52:36 volumio volumio[839]: info: CoreStateMachine::stPlaybackTimer May 02 20:52:36 volumio volumio[839]: info: CoreStateMachine::updateTrackBlock May 02 20:52:36 volumio volumio[839]: info: CorePlayQueue::getTrackBlock May 02 20:52:36 volumio volumio[839]: info: CoreStateMachine::pushState May 02 20:52:36 volumio volumio[839]: info: CorePlayQueue::getTrack 3 May 02 20:52:36 volumio volumio[839]: info: CoreCommandRouter::volumioPushState May 02 20:52:36 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 20:52:36 volumio volumio[839]: info: CoreStateMachine::serviceStop May 02 20:52:36 volumio volumio[839]: info: CorePlayQueue::getTrack 3 May 02 20:52:36 volumio volumio[839]: info: CoreCommandRouter::serviceStop May 02 20:52:36 volumio volumio[839]: info: ControllerMpd::stop May 02 20:52:36 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand stop May 02 20:52:36 volumio volumio[839]: info: May 02 20:52:36 volumio volumio[839]: ---------------------------- MPD announces state update: player May 02 20:52:36 volumio volumio[839]: info: ControllerMpd::getState May 02 20:52:36 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand status May 02 20:52:36 volumio volumio[839]: info: CoreStateMachine::play index undefined May 02 20:52:36 volumio volumio[839]: info: CoreStateMachine::setConsumeUpdateService undefined May 02 20:52:36 volumio volumio[839]: info: CorePlayQueue::getTrack 13 May 02 20:52:36 volumio volumio[839]: info: CoreStateMachine::startPlaybackTimer May 02 20:52:36 volumio volumio[839]: info: CorePlayQueue::getTrack 13 May 02 20:52:36 volumio volumio[839]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/song/AGA-圆-《Wonderful U》粤语版.flac May 02 20:52:36 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand stop May 02 20:52:36 volumio volumio[839]: info: CoreStateMachine::updateTrackBlock May 02 20:52:36 volumio volumio[839]: info: CorePlayQueue::getTrackBlock May 02 20:52:36 volumio volumio[839]: verbose: ControllerMpd::parseState May 02 20:52:36 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 20:52:36 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand clear May 02 20:52:36 volumio volumio[839]: info: May 02 20:52:36 volumio volumio[839]: ---------------------------- MPD announces system playlist update May 02 20:52:36 volumio volumio[839]: info: Ignoring MPD Status Update May 02 20:52:36 volumio volumio[839]: verbose: ControllerMpd::parseTrackInfo May 02 20:52:36 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/song/AGA-圆-《Wonderful U》粤语版.flac" May 02 20:52:36 volumio volumio[839]: info: ControllerMpd::pushState May 02 20:52:36 volumio volumio[839]: info: CoreCommandRouter::servicePushState May 02 20:52:36 volumio volumio[839]: info: CoreStateMachine::pushState May 02 20:52:36 volumio volumio[839]: info: CorePlayQueue::getTrack 13 May 02 20:52:36 volumio volumio[839]: info: CoreCommandRouter::volumioPushState May 02 20:52:36 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 20:52:36 volumio volumio[839]: info: CorePlayQueue::getTrack 13 May 02 20:52:36 volumio volumio[839]: 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":"No Expectations","artist":"AG","album":"Discover: Songs Of The Rolling Stones Vol. 2","uri":"INTERNAL/song/AG-No Expectations.flac","trackType":"flac"} May 02 20:52:36 volumio volumio[839]: verbose: CURRENT POSITION 13 May 02 20:52:36 volumio volumio[839]: info: CoreStateMachine::syncState stateService stop May 02 20:52:36 volumio volumio[839]: info: CoreStateMachine::syncState currentStatus stop May 02 20:52:36 volumio volumio[839]: info: CoreStateMachine::pushState May 02 20:52:36 volumio volumio[839]: info: CorePlayQueue::getTrack 13 May 02 20:52:36 volumio volumio[839]: info: CoreCommandRouter::volumioPushState May 02 20:52:36 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 20:52:36 volumio volumio[839]: info: No code May 02 20:52:36 volumio volumio[839]: info: CoreStateMachine::pushState May 02 20:52:36 volumio volumio[839]: info: CorePlayQueue::getTrack 13 May 02 20:52:36 volumio volumio[839]: info: CoreCommandRouter::volumioPushState May 02 20:52:36 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 20:52:36 volumio volumio[839]: info: ------------------------------ 23ms May 02 20:52:36 volumio volumio[839]: error: updateQueue error: null May 02 20:52:36 volumio volumio[839]: info: May 02 20:52:36 volumio volumio[839]: ---------------------------- MPD announces system playlist update May 02 20:52:36 volumio volumio[839]: info: Ignoring MPD Status Update May 02 20:52:36 volumio volumio[839]: info: ------------------------------ 19ms May 02 20:52:36 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand play May 02 20:52:36 volumio volumio[839]: info: ------------------------------ 10ms May 02 20:52:36 volumio volumio[839]: info: May 02 20:52:36 volumio volumio[839]: ---------------------------- MPD announces state update: player May 02 20:52:36 volumio volumio[839]: info: ControllerMpd::getState May 02 20:52:36 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand status May 02 20:52:36 volumio volumio[839]: info: May 02 20:52:36 volumio volumio[839]: ---------------------------- MPD announces state update: player May 02 20:52:36 volumio volumio[839]: info: ControllerMpd::getState May 02 20:52:36 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand status May 02 20:52:36 volumio volumio[839]: verbose: ControllerMpd::parseState May 02 20:52:36 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 20:52:36 volumio volumio[839]: verbose: ControllerMpd::parseState May 02 20:52:36 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 20:52:36 volumio volumio[839]: verbose: ControllerMpd::parseTrackInfo May 02 20:52:36 volumio volumio[839]: info: ControllerMpd::pushState May 02 20:52:36 volumio volumio[839]: info: CoreCommandRouter::servicePushState May 02 20:52:36 volumio volumio[839]: info: CorePlayQueue::getTrack 13 May 02 20:52:36 volumio volumio[839]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":254,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"565 Kbps","isStreaming":false,"title":"圆-《Wonderful U》粤语版","artist":"AGA","album":"Ginadoll","uri":"INTERNAL/song/AGA-圆-《Wonderful U》粤语版.flac","trackType":"flac"} May 02 20:52:36 volumio volumio[839]: verbose: CURRENT POSITION 13 May 02 20:52:36 volumio volumio[839]: info: CoreStateMachine::syncState stateService play May 02 20:52:36 volumio volumio[839]: info: CoreStateMachine::syncState currentStatus stop May 02 20:52:36 volumio volumio[839]: info: CoreStateMachine::pushState May 02 20:52:36 volumio volumio[839]: info: CorePlayQueue::getTrack 13 May 02 20:52:36 volumio volumio[839]: info: CoreCommandRouter::volumioPushState May 02 20:52:36 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 20:52:36 volumio volumio[839]: info: ------------------------------ 11ms May 02 20:52:36 volumio volumio[839]: verbose: ControllerMpd::parseTrackInfo May 02 20:52:36 volumio volumio[839]: info: ControllerMpd::pushState May 02 20:52:36 volumio volumio[839]: info: CoreCommandRouter::servicePushState May 02 20:52:36 volumio volumio[839]: info: CorePlayQueue::getTrack 13 May 02 20:52:36 volumio volumio[839]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":254,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"565 Kbps","isStreaming":false,"title":"圆-《Wonderful U》粤语版","artist":"AGA","album":"Ginadoll","uri":"INTERNAL/song/AGA-圆-《Wonderful U》粤语版.flac","trackType":"flac"} May 02 20:52:36 volumio volumio[839]: verbose: CURRENT POSITION 13 May 02 20:52:36 volumio volumio[839]: info: CoreStateMachine::syncState stateService play May 02 20:52:36 volumio volumio[839]: info: CoreStateMachine::syncState currentStatus play May 02 20:52:36 volumio volumio[839]: info: Received an update from plugin. extracting info from payload May 02 20:52:36 volumio volumio[839]: info: CoreStateMachine::pushState May 02 20:52:36 volumio volumio[839]: info: CorePlayQueue::getTrack 13 May 02 20:52:36 volumio volumio[839]: info: CoreCommandRouter::volumioPushState May 02 20:52:36 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 20:52:36 volumio volumio[839]: info: CoreStateMachine::pushState May 02 20:52:36 volumio volumio[839]: info: CorePlayQueue::getTrack 13 May 02 20:52:36 volumio volumio[839]: info: CoreCommandRouter::volumioPushState May 02 20:52:36 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 20:52:36 volumio volumio[839]: info: ------------------------------ 25ms May 02 20:52:36 volumio volumio[839]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/AGA/Ginadoll/74b24fa0-0186-4d9f-bef7-e02c4d9007b1.png' May 02 20:52:37 volumio volumio[839]: info: CoreCommandRouter::volumioSeek May 02 20:52:37 volumio volumio[839]: info: CoreStateMachine::seek May 02 20:52:37 volumio volumio[839]: info: CorePlayQueue::getTrack 13 May 02 20:52:37 volumio volumio[839]: info: TRACKBLOCK {"uri":"mnt/INTERNAL/song/AGA-圆-《Wonderful U》粤语版.flac","service":"mpd","name":"圆-《Wonderful U》粤语版","artist":"AGA","album":"Ginadoll","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=994&web=AGA/Ginadoll/extralarge&path=%2FINTERNAL%2Fsong&metadata=false","duration":254,"trackType":"flac","samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2} May 02 20:52:37 volumio volumio[839]: info: CoreStateMachine::startPlaybackTimer May 02 20:52:37 volumio volumio[839]: info: CorePlayQueue::getTrack 13 May 02 20:52:37 volumio volumio[839]: info: ControllerMpd::seek May 02 20:52:37 volumio volumio[839]: info: CoreStateMachine::pushState May 02 20:52:37 volumio volumio[839]: info: CorePlayQueue::getTrack 13 May 02 20:52:37 volumio volumio[839]: info: CoreCommandRouter::volumioPushState May 02 20:52:37 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 20:52:38 volumio volumio[839]: error: null May 02 20:52:38 volumio volumio[839]: info: May 02 20:52:38 volumio volumio[839]: ---------------------------- MPD announces state update: player May 02 20:52:38 volumio volumio[839]: info: ControllerMpd::getState May 02 20:52:38 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand status May 02 20:52:38 volumio volumio[839]: verbose: ControllerMpd::parseState May 02 20:52:38 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 20:52:38 volumio volumio[839]: verbose: ControllerMpd::parseTrackInfo May 02 20:52:38 volumio volumio[839]: info: ControllerMpd::pushState May 02 20:52:38 volumio volumio[839]: info: CoreCommandRouter::servicePushState May 02 20:52:38 volumio volumio[839]: info: CorePlayQueue::getTrack 13 May 02 20:52:38 volumio volumio[839]: verbose: STATE SERVICE {"status":"play","position":0,"seek":40000,"duration":254,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"521 Kbps","isStreaming":false,"title":"圆-《Wonderful U》粤语版","artist":"AGA","album":"Ginadoll","uri":"INTERNAL/song/AGA-圆-《Wonderful U》粤语版.flac","trackType":"flac"} May 02 20:52:38 volumio volumio[839]: verbose: CURRENT POSITION 13 May 02 20:52:38 volumio volumio[839]: info: CoreStateMachine::syncState stateService play May 02 20:52:38 volumio volumio[839]: info: CoreStateMachine::syncState currentStatus play May 02 20:52:38 volumio volumio[839]: info: Received an update from plugin. extracting info from payload May 02 20:52:38 volumio volumio[839]: info: CoreStateMachine::pushState May 02 20:52:38 volumio volumio[839]: info: CorePlayQueue::getTrack 13 May 02 20:52:38 volumio volumio[839]: info: CoreCommandRouter::volumioPushState May 02 20:52:38 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 20:52:38 volumio volumio[839]: info: CoreStateMachine::pushState May 02 20:52:38 volumio volumio[839]: info: CorePlayQueue::getTrack 13 May 02 20:52:38 volumio volumio[839]: info: CoreCommandRouter::volumioPushState May 02 20:52:38 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 20:52:38 volumio volumio[839]: info: ------------------------------ 7ms May 02 20:52:40 volumio volumio[839]: info: VolumeController::SetAlsaVolume30 May 02 20:52:40 volumio volumio[839]: info: CoreStateMachine::pushState May 02 20:52:40 volumio volumio[839]: info: CorePlayQueue::getTrack 13 May 02 20:52:40 volumio volumio[839]: info: CoreCommandRouter::volumioPushState May 02 20:52:40 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 20:52:41 volumio volumio[839]: info: VolumeController::SetAlsaVolume25 May 02 20:52:41 volumio volumio[839]: info: CoreStateMachine::pushState May 02 20:52:41 volumio volumio[839]: info: CorePlayQueue::getTrack 13 May 02 20:52:41 volumio volumio[839]: info: CoreCommandRouter::volumioPushState May 02 20:52:41 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 20:52:41 volumio volumio[839]: info: VolumeController::SetAlsaVolume20 May 02 20:52:41 volumio volumio[839]: info: CoreStateMachine::pushState May 02 20:52:41 volumio volumio[839]: info: CorePlayQueue::getTrack 13 May 02 20:52:41 volumio volumio[839]: info: CoreCommandRouter::volumioPushState May 02 20:52:41 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 20:52:41 volumio volumio[839]: info: VolumeController::SetAlsaVolume15 May 02 20:52:41 volumio volumio[839]: info: CoreStateMachine::pushState May 02 20:52:41 volumio volumio[839]: info: CorePlayQueue::getTrack 13 May 02 20:52:41 volumio volumio[839]: info: CoreCommandRouter::volumioPushState May 02 20:52:41 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 20:52:42 volumio volumio[839]: info: VolumeController::SetAlsaVolume10 May 02 20:52:42 volumio volumio[839]: info: CoreStateMachine::pushState May 02 20:52:42 volumio volumio[839]: info: CorePlayQueue::getTrack 13 May 02 20:52:42 volumio volumio[839]: info: CoreCommandRouter::volumioPushState May 02 20:52:42 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 20:52:47 volumio volumio[839]: info: CoreCommandRouter::volumioNext May 02 20:52:47 volumio volumio[839]: info: CoreStateMachine::next May 02 20:52:47 volumio volumio[839]: info: CoreStateMachine::stop May 02 20:52:47 volumio volumio[839]: info: CoreStateMachine::setConsumeUpdateService undefined May 02 20:52:47 volumio volumio[839]: UNSET VOLATILE May 02 20:52:47 volumio volumio[839]: info: CoreStateMachine::stPlaybackTimer May 02 20:52:47 volumio volumio[839]: info: CoreStateMachine::updateTrackBlock May 02 20:52:47 volumio volumio[839]: info: CorePlayQueue::getTrackBlock May 02 20:52:47 volumio volumio[839]: info: CoreStateMachine::pushState May 02 20:52:47 volumio volumio[839]: info: CorePlayQueue::getTrack 13 May 02 20:52:47 volumio volumio[839]: info: CoreCommandRouter::volumioPushState May 02 20:52:47 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 20:52:47 volumio volumio[839]: info: CoreStateMachine::serviceStop May 02 20:52:47 volumio volumio[839]: info: CorePlayQueue::getTrack 13 May 02 20:52:47 volumio volumio[839]: info: CoreCommandRouter::serviceStop May 02 20:52:47 volumio volumio[839]: info: ControllerMpd::stop May 02 20:52:47 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand stop May 02 20:52:47 volumio volumio[839]: info: May 02 20:52:47 volumio volumio[839]: ---------------------------- MPD announces state update: player May 02 20:52:47 volumio volumio[839]: info: ControllerMpd::getState May 02 20:52:47 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand status May 02 20:52:47 volumio volumio[839]: info: CoreStateMachine::play index undefined May 02 20:52:47 volumio volumio[839]: info: CoreStateMachine::setConsumeUpdateService undefined May 02 20:52:47 volumio volumio[839]: info: CorePlayQueue::getTrack 6 May 02 20:52:47 volumio volumio[839]: info: CoreStateMachine::startPlaybackTimer May 02 20:52:47 volumio volumio[839]: info: CorePlayQueue::getTrack 6 May 02 20:52:47 volumio volumio[839]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/song/AGA-See You Next Time.flac May 02 20:52:47 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand stop May 02 20:52:47 volumio volumio[839]: info: CoreStateMachine::updateTrackBlock May 02 20:52:47 volumio volumio[839]: info: CorePlayQueue::getTrackBlock May 02 20:52:47 volumio volumio[839]: verbose: ControllerMpd::parseState May 02 20:52:47 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 20:52:47 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand clear May 02 20:52:47 volumio volumio[839]: verbose: ControllerMpd::parseTrackInfo May 02 20:52:47 volumio volumio[839]: info: ControllerMpd::pushState May 02 20:52:47 volumio volumio[839]: info: CoreCommandRouter::servicePushState May 02 20:52:47 volumio volumio[839]: info: CoreStateMachine::pushState May 02 20:52:47 volumio volumio[839]: info: CorePlayQueue::getTrack 6 May 02 20:52:47 volumio volumio[839]: info: CoreCommandRouter::volumioPushState May 02 20:52:47 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 20:52:47 volumio volumio[839]: info: CorePlayQueue::getTrack 6 May 02 20:52:47 volumio volumio[839]: 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":"圆-《Wonderful U》粤语版","artist":"AGA","album":"Ginadoll","uri":"INTERNAL/song/AGA-圆-《Wonderful U》粤语版.flac","trackType":"flac"} May 02 20:52:47 volumio volumio[839]: verbose: CURRENT POSITION 6 May 02 20:52:47 volumio volumio[839]: info: CoreStateMachine::syncState stateService stop May 02 20:52:47 volumio volumio[839]: info: CoreStateMachine::syncState currentStatus stop May 02 20:52:47 volumio volumio[839]: info: CoreStateMachine::pushState May 02 20:52:47 volumio volumio[839]: info: CorePlayQueue::getTrack 6 May 02 20:52:47 volumio volumio[839]: info: CoreCommandRouter::volumioPushState May 02 20:52:47 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 20:52:47 volumio volumio[839]: info: No code May 02 20:52:47 volumio volumio[839]: info: CoreStateMachine::pushState May 02 20:52:47 volumio volumio[839]: info: CorePlayQueue::getTrack 6 May 02 20:52:47 volumio volumio[839]: info: CoreCommandRouter::volumioPushState May 02 20:52:47 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 20:52:47 volumio volumio[839]: info: ------------------------------ 15ms May 02 20:52:47 volumio volumio[839]: info: May 02 20:52:47 volumio volumio[839]: ---------------------------- MPD announces system playlist update May 02 20:52:47 volumio volumio[839]: info: Ignoring MPD Status Update May 02 20:52:47 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/song/AGA-See You Next Time.flac" May 02 20:52:47 volumio volumio[839]: error: updateQueue error: null May 02 20:52:47 volumio volumio[839]: info: May 02 20:52:47 volumio volumio[839]: ---------------------------- MPD announces system playlist update May 02 20:52:47 volumio volumio[839]: info: Ignoring MPD Status Update May 02 20:52:47 volumio volumio[839]: info: ------------------------------ 8ms May 02 20:52:47 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand play May 02 20:52:47 volumio volumio[839]: info: ------------------------------ 1ms May 02 20:52:47 volumio volumio[839]: info: May 02 20:52:47 volumio volumio[839]: ---------------------------- MPD announces state update: player May 02 20:52:47 volumio volumio[839]: info: ControllerMpd::getState May 02 20:52:47 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand status May 02 20:52:47 volumio volumio[839]: verbose: ControllerMpd::parseState May 02 20:52:47 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 20:52:47 volumio volumio[839]: info: May 02 20:52:47 volumio volumio[839]: ---------------------------- MPD announces state update: player May 02 20:52:47 volumio volumio[839]: info: ControllerMpd::getState May 02 20:52:47 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand status May 02 20:52:47 volumio volumio[839]: verbose: ControllerMpd::parseTrackInfo May 02 20:52:47 volumio volumio[839]: verbose: ControllerMpd::parseState May 02 20:52:47 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 20:52:47 volumio volumio[839]: info: ControllerMpd::pushState May 02 20:52:47 volumio volumio[839]: info: CoreCommandRouter::servicePushState May 02 20:52:47 volumio volumio[839]: info: CorePlayQueue::getTrack 6 May 02 20:52:47 volumio volumio[839]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":258,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"326 Kbps","isStreaming":false,"title":"See You Next Time","artist":"AGA","album":"So Called Love Songs","uri":"INTERNAL/song/AGA-See You Next Time.flac","trackType":"flac"} May 02 20:52:47 volumio volumio[839]: verbose: CURRENT POSITION 6 May 02 20:52:47 volumio volumio[839]: info: CoreStateMachine::syncState stateService play May 02 20:52:47 volumio volumio[839]: info: CoreStateMachine::syncState currentStatus stop May 02 20:52:47 volumio volumio[839]: info: CoreStateMachine::pushState May 02 20:52:47 volumio volumio[839]: info: CorePlayQueue::getTrack 6 May 02 20:52:47 volumio volumio[839]: info: CoreCommandRouter::volumioPushState May 02 20:52:47 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 20:52:47 volumio volumio[839]: info: ------------------------------ 14ms May 02 20:52:47 volumio volumio[839]: verbose: ControllerMpd::parseTrackInfo May 02 20:52:47 volumio volumio[839]: info: ControllerMpd::pushState May 02 20:52:47 volumio volumio[839]: info: CoreCommandRouter::servicePushState May 02 20:52:47 volumio volumio[839]: info: CorePlayQueue::getTrack 6 May 02 20:52:47 volumio volumio[839]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":258,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"446 Kbps","isStreaming":false,"title":"See You Next Time","artist":"AGA","album":"So Called Love Songs","uri":"INTERNAL/song/AGA-See You Next Time.flac","trackType":"flac"} May 02 20:52:47 volumio volumio[839]: verbose: CURRENT POSITION 6 May 02 20:52:47 volumio volumio[839]: info: CoreStateMachine::syncState stateService play May 02 20:52:47 volumio volumio[839]: info: CoreStateMachine::syncState currentStatus play May 02 20:52:47 volumio volumio[839]: info: Received an update from plugin. extracting info from payload May 02 20:52:47 volumio volumio[839]: info: CoreStateMachine::pushState May 02 20:52:47 volumio volumio[839]: info: CorePlayQueue::getTrack 6 May 02 20:52:47 volumio volumio[839]: info: CoreCommandRouter::volumioPushState May 02 20:52:47 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 20:52:47 volumio volumio[839]: info: CoreStateMachine::pushState May 02 20:52:47 volumio volumio[839]: info: CorePlayQueue::getTrack 6 May 02 20:52:47 volumio volumio[839]: info: CoreCommandRouter::volumioPushState May 02 20:52:47 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 20:52:47 volumio volumio[839]: info: ------------------------------ 34ms May 02 20:52:47 volumio volumio[839]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/AGA/So%20Called%20Love%20Songs/2e4fdaf7-f59d-4d21-95dc-78caf79274de.jpg' May 02 20:52:49 volumio volumio[839]: info: CoreCommandRouter::volumioaddPlayList May 02 20:52:49 volumio volumio[839]: info: CoreStateMachine::getQueue May 02 20:52:49 volumio volumio[839]: info: CorePlayQueue::getQueue May 02 20:52:49 volumio volumio[839]: info: CoreStateMachine::addQueueItems May 02 20:52:49 volumio volumio[839]: info: CorePlayQueue::addQueueItems May 02 20:52:49 volumio volumio[839]: info: Adding Item to queue: music-library/INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 一生何求.dff May 02 20:52:49 volumio volumio[839]: info: Exploding uri music-library/INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 一生何求.dff in service mpd May 02 20:52:49 volumio volumio[839]: info: Adding Item to queue: music-library/INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 今宵多珍重.dff May 02 20:52:49 volumio volumio[839]: info: Exploding uri music-library/INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 今宵多珍重.dff in service mpd May 02 20:52:49 volumio volumio[839]: info: Adding Item to queue: music-library/INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 你的浅笑.dff May 02 20:52:49 volumio volumio[839]: info: Exploding uri music-library/INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 你的浅笑.dff in service mpd May 02 20:52:49 volumio volumio[839]: info: Adding Item to queue: music-library/INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 倾城.dff May 02 20:52:49 volumio volumio[839]: info: Exploding uri music-library/INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 倾城.dff in service mpd May 02 20:52:49 volumio volumio[839]: info: Adding Item to queue: music-library/INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 再见二丁目.dff May 02 20:52:49 volumio volumio[839]: info: Exploding uri music-library/INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 再见二丁目.dff in service mpd May 02 20:52:49 volumio volumio[839]: info: Adding Item to queue: music-library/INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 哪儿.dff May 02 20:52:49 volumio volumio[839]: info: Exploding uri music-library/INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 哪儿.dff in service mpd May 02 20:52:49 volumio volumio[839]: info: Adding Item to queue: music-library/INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 夜机.dff May 02 20:52:49 volumio volumio[839]: info: Exploding uri music-library/INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 夜机.dff in service mpd May 02 20:52:49 volumio volumio[839]: info: Adding Item to queue: music-library/INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 情愿.dff May 02 20:52:49 volumio volumio[839]: info: Exploding uri music-library/INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 情愿.dff in service mpd May 02 20:52:49 volumio volumio[839]: info: Adding Item to queue: music-library/INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 最爱.dff May 02 20:52:49 volumio volumio[839]: info: Exploding uri music-library/INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 最爱.dff in service mpd May 02 20:52:49 volumio volumio[839]: info: Adding Item to queue: music-library/INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 月半小夜曲.dff May 02 20:52:49 volumio volumio[839]: info: Exploding uri music-library/INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 月半小夜曲.dff in service mpd May 02 20:52:49 volumio volumio[839]: info: Adding Item to queue: music-library/INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 爱与痛的边缘.dff May 02 20:52:49 volumio volumio[839]: info: Exploding uri music-library/INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 爱与痛的边缘.dff in service mpd May 02 20:52:49 volumio volumio[839]: info: Adding Item to queue: music-library/INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 痴心换情深.dff May 02 20:52:49 volumio volumio[839]: info: Exploding uri music-library/INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 痴心换情深.dff in service mpd May 02 20:52:49 volumio volumio[839]: info: ALBUMART /albumart?cacheid=994&web=//extralarge&path=%2Fmnt%2FINTERNAL%2F%E9%98%BF%E6%A2%A8%C2%B7%E7%B2%A4%20-%20%E6%9C%80%E7%88%B1%5BDSD%5D%2F%E9%98%BF%E6%A2%A8.%E7%B2%A4%20-%20%E6%9C%80%E7%88%B1%20-%20%E4%B8%80%E7%94%9F%E4%BD%95%E6%B1%82.dff&metadata=false May 02 20:52:49 volumio volumio[839]: info: URI /mnt/INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 一生何求.dff May 02 20:52:49 volumio volumio[839]: info: ALBUMART /albumart?cacheid=994&web=//extralarge&path=%2Fmnt%2FINTERNAL%2F%E9%98%BF%E6%A2%A8%C2%B7%E7%B2%A4%20-%20%E6%9C%80%E7%88%B1%5BDSD%5D%2F%E9%98%BF%E6%A2%A8.%E7%B2%A4%20-%20%E6%9C%80%E7%88%B1%20-%20%E4%BB%8A%E5%AE%B5%E5%A4%9A%E7%8F%8D%E9%87%8D.dff&metadata=false May 02 20:52:49 volumio volumio[839]: info: URI /mnt/INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 今宵多珍重.dff May 02 20:52:49 volumio volumio[839]: info: ALBUMART /albumart?cacheid=994&web=//extralarge&path=%2Fmnt%2FINTERNAL%2F%E9%98%BF%E6%A2%A8%C2%B7%E7%B2%A4%20-%20%E6%9C%80%E7%88%B1%5BDSD%5D%2F%E9%98%BF%E6%A2%A8.%E7%B2%A4%20-%20%E6%9C%80%E7%88%B1%20-%20%E4%BD%A0%E7%9A%84%E6%B5%85%E7%AC%91.dff&metadata=false May 02 20:52:49 volumio volumio[839]: info: URI /mnt/INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 你的浅笑.dff May 02 20:52:49 volumio volumio[839]: info: ALBUMART /albumart?cacheid=994&web=//extralarge&path=%2Fmnt%2FINTERNAL%2F%E9%98%BF%E6%A2%A8%C2%B7%E7%B2%A4%20-%20%E6%9C%80%E7%88%B1%5BDSD%5D%2F%E9%98%BF%E6%A2%A8.%E7%B2%A4%20-%20%E6%9C%80%E7%88%B1%20-%20%E5%80%BE%E5%9F%8E.dff&metadata=false May 02 20:52:49 volumio volumio[839]: info: URI /mnt/INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 倾城.dff May 02 20:52:49 volumio volumio[839]: info: ALBUMART /albumart?cacheid=994&web=//extralarge&path=%2Fmnt%2FINTERNAL%2F%E9%98%BF%E6%A2%A8%C2%B7%E7%B2%A4%20-%20%E6%9C%80%E7%88%B1%5BDSD%5D%2F%E9%98%BF%E6%A2%A8.%E7%B2%A4%20-%20%E6%9C%80%E7%88%B1%20-%20%E5%86%8D%E8%A7%81%E4%BA%8C%E4%B8%81%E7%9B%AE.dff&metadata=false May 02 20:52:49 volumio volumio[839]: info: URI /mnt/INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 再见二丁目.dff May 02 20:52:49 volumio volumio[839]: info: ALBUMART /albumart?cacheid=994&web=//extralarge&path=%2Fmnt%2FINTERNAL%2F%E9%98%BF%E6%A2%A8%C2%B7%E7%B2%A4%20-%20%E6%9C%80%E7%88%B1%5BDSD%5D%2F%E9%98%BF%E6%A2%A8.%E7%B2%A4%20-%20%E6%9C%80%E7%88%B1%20-%20%E5%93%AA%E5%84%BF.dff&metadata=false May 02 20:52:49 volumio volumio[839]: info: URI /mnt/INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 哪儿.dff May 02 20:52:49 volumio volumio[839]: info: ALBUMART /albumart?cacheid=994&web=//extralarge&path=%2Fmnt%2FINTERNAL%2F%E9%98%BF%E6%A2%A8%C2%B7%E7%B2%A4%20-%20%E6%9C%80%E7%88%B1%5BDSD%5D%2F%E9%98%BF%E6%A2%A8.%E7%B2%A4%20-%20%E6%9C%80%E7%88%B1%20-%20%E5%A4%9C%E6%9C%BA.dff&metadata=false May 02 20:52:49 volumio volumio[839]: info: URI /mnt/INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 夜机.dff May 02 20:52:49 volumio volumio[839]: info: ALBUMART /albumart?cacheid=994&web=//extralarge&path=%2Fmnt%2FINTERNAL%2F%E9%98%BF%E6%A2%A8%C2%B7%E7%B2%A4%20-%20%E6%9C%80%E7%88%B1%5BDSD%5D%2F%E9%98%BF%E6%A2%A8.%E7%B2%A4%20-%20%E6%9C%80%E7%88%B1%20-%20%E6%83%85%E6%84%BF.dff&metadata=false May 02 20:52:49 volumio volumio[839]: info: URI /mnt/INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 情愿.dff May 02 20:52:49 volumio volumio[839]: info: ALBUMART /albumart?cacheid=994&web=//extralarge&path=%2Fmnt%2FINTERNAL%2F%E9%98%BF%E6%A2%A8%C2%B7%E7%B2%A4%20-%20%E6%9C%80%E7%88%B1%5BDSD%5D%2F%E9%98%BF%E6%A2%A8.%E7%B2%A4%20-%20%E6%9C%80%E7%88%B1%20-%20%E6%9C%80%E7%88%B1.dff&metadata=false May 02 20:52:49 volumio volumio[839]: info: URI /mnt/INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 最爱.dff May 02 20:52:49 volumio volumio[839]: info: ALBUMART /albumart?cacheid=994&web=//extralarge&path=%2Fmnt%2FINTERNAL%2F%E9%98%BF%E6%A2%A8%C2%B7%E7%B2%A4%20-%20%E6%9C%80%E7%88%B1%5BDSD%5D%2F%E9%98%BF%E6%A2%A8.%E7%B2%A4%20-%20%E6%9C%80%E7%88%B1%20-%20%E6%9C%88%E5%8D%8A%E5%B0%8F%E5%A4%9C%E6%9B%B2.dff&metadata=false May 02 20:52:49 volumio volumio[839]: info: URI /mnt/INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 月半小夜曲.dff May 02 20:52:49 volumio volumio[839]: info: ALBUMART /albumart?cacheid=994&web=//extralarge&path=%2Fmnt%2FINTERNAL%2F%E9%98%BF%E6%A2%A8%C2%B7%E7%B2%A4%20-%20%E6%9C%80%E7%88%B1%5BDSD%5D%2F%E9%98%BF%E6%A2%A8.%E7%B2%A4%20-%20%E6%9C%80%E7%88%B1%20-%20%E7%88%B1%E4%B8%8E%E7%97%9B%E7%9A%84%E8%BE%B9%E7%BC%98.dff&metadata=false May 02 20:52:49 volumio volumio[839]: info: URI /mnt/INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 爱与痛的边缘.dff May 02 20:52:49 volumio volumio[839]: info: ALBUMART /albumart?cacheid=994&web=//extralarge&path=%2Fmnt%2FINTERNAL%2F%E9%98%BF%E6%A2%A8%C2%B7%E7%B2%A4%20-%20%E6%9C%80%E7%88%B1%5BDSD%5D%2F%E9%98%BF%E6%A2%A8.%E7%B2%A4%20-%20%E6%9C%80%E7%88%B1%20-%20%E7%97%B4%E5%BF%83%E6%8D%A2%E6%83%85%E6%B7%B1.dff&metadata=false May 02 20:52:49 volumio volumio[839]: info: URI /mnt/INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 痴心换情深.dff May 02 20:52:49 volumio volumio[839]: info: CorePlayQueue::saveQueue May 02 20:52:49 volumio volumio[839]: info: CoreCommandRouter::volumioPushQueue May 02 20:52:49 volumio volumio[839]: info: CoreStateMachine::updateTrackBlock May 02 20:52:49 volumio volumio[839]: info: CorePlayQueue::getTrackBlock May 02 20:52:49 volumio volumio[839]: info: CoreCommandRouter::volumioPlay May 02 20:52:49 volumio volumio[839]: UNSET VOLATILE May 02 20:52:49 volumio volumio[839]: info: CoreStateMachine::play index 216 May 02 20:52:49 volumio volumio[839]: info: CoreStateMachine::setConsumeUpdateService undefined May 02 20:52:49 volumio volumio[839]: info: CoreStateMachine::stop May 02 20:52:49 volumio volumio[839]: info: CoreStateMachine::setConsumeUpdateService undefined May 02 20:52:49 volumio volumio[839]: UNSET VOLATILE May 02 20:52:49 volumio volumio[839]: info: CoreStateMachine::stPlaybackTimer May 02 20:52:49 volumio volumio[839]: info: CoreStateMachine::updateTrackBlock May 02 20:52:49 volumio volumio[839]: info: CorePlayQueue::getTrackBlock May 02 20:52:49 volumio volumio[839]: info: CoreStateMachine::pushState May 02 20:52:49 volumio volumio[839]: info: CorePlayQueue::getTrack 6 May 02 20:52:49 volumio volumio[839]: info: CoreCommandRouter::volumioPushState May 02 20:52:49 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 20:52:49 volumio volumio[839]: info: CoreStateMachine::serviceStop May 02 20:52:49 volumio volumio[839]: info: CorePlayQueue::getTrack 6 May 02 20:52:49 volumio volumio[839]: info: CoreCommandRouter::serviceStop May 02 20:52:49 volumio volumio[839]: info: ControllerMpd::stop May 02 20:52:49 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand stop May 02 20:52:49 volumio volumio[839]: info: May 02 20:52:49 volumio volumio[839]: ---------------------------- MPD announces state update: player May 02 20:52:49 volumio volumio[839]: info: ControllerMpd::getState May 02 20:52:49 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand status May 02 20:52:49 volumio volumio[839]: info: CoreStateMachine::play index undefined May 02 20:52:49 volumio volumio[839]: info: CoreStateMachine::setConsumeUpdateService undefined May 02 20:52:49 volumio volumio[839]: info: CorePlayQueue::getTrack 216 May 02 20:52:49 volumio volumio[839]: info: CoreStateMachine::startPlaybackTimer May 02 20:52:49 volumio volumio[839]: info: CorePlayQueue::getTrack 216 May 02 20:52:49 volumio volumio[839]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 情愿.dff May 02 20:52:49 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand stop May 02 20:52:49 volumio volumio[839]: verbose: ControllerMpd::parseState May 02 20:52:49 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 20:52:49 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand clear May 02 20:52:49 volumio volumio[839]: info: May 02 20:52:49 volumio volumio[839]: ---------------------------- MPD announces system playlist update May 02 20:52:49 volumio volumio[839]: info: Ignoring MPD Status Update May 02 20:52:49 volumio volumio[839]: verbose: ControllerMpd::parseTrackInfo May 02 20:52:49 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 情愿.dff" May 02 20:52:49 volumio volumio[839]: info: ControllerMpd::pushState May 02 20:52:49 volumio volumio[839]: info: CoreCommandRouter::servicePushState May 02 20:52:49 volumio volumio[839]: info: CoreStateMachine::pushState May 02 20:52:49 volumio volumio[839]: info: CorePlayQueue::getTrack 216 May 02 20:52:49 volumio volumio[839]: info: CoreCommandRouter::volumioPushState May 02 20:52:49 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 20:52:49 volumio volumio[839]: info: CorePlayQueue::getTrack 216 May 02 20:52:49 volumio volumio[839]: 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":"See You Next Time","artist":"AGA","album":"So Called Love Songs","uri":"INTERNAL/song/AGA-See You Next Time.flac","trackType":"flac"} May 02 20:52:49 volumio volumio[839]: verbose: CURRENT POSITION 216 May 02 20:52:49 volumio volumio[839]: info: CoreStateMachine::syncState stateService stop May 02 20:52:49 volumio volumio[839]: info: CoreStateMachine::syncState currentStatus stop May 02 20:52:49 volumio volumio[839]: info: CoreStateMachine::pushState May 02 20:52:49 volumio volumio[839]: info: CorePlayQueue::getTrack 216 May 02 20:52:49 volumio volumio[839]: info: CoreCommandRouter::volumioPushState May 02 20:52:49 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 20:52:49 volumio volumio[839]: info: No code May 02 20:52:49 volumio volumio[839]: info: CoreStateMachine::pushState May 02 20:52:49 volumio volumio[839]: info: CorePlayQueue::getTrack 216 May 02 20:52:49 volumio volumio[839]: info: CoreCommandRouter::volumioPushState May 02 20:52:49 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 20:52:49 volumio volumio[839]: info: ------------------------------ 14ms May 02 20:52:49 volumio volumio[839]: error: updateQueue error: null May 02 20:52:49 volumio volumio[839]: info: May 02 20:52:49 volumio volumio[839]: ---------------------------- MPD announces system playlist update May 02 20:52:49 volumio volumio[839]: info: Ignoring MPD Status Update May 02 20:52:49 volumio volumio[839]: info: ------------------------------ 11ms May 02 20:52:49 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand play May 02 20:52:49 volumio volumio[839]: info: ------------------------------ 4ms May 02 20:52:49 volumio volumio[839]: info: May 02 20:52:49 volumio volumio[839]: ---------------------------- MPD announces state update: player May 02 20:52:49 volumio volumio[839]: info: ControllerMpd::getState May 02 20:52:49 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand status May 02 20:52:49 volumio volumio[839]: info: May 02 20:52:49 volumio volumio[839]: ---------------------------- MPD announces state update: player May 02 20:52:49 volumio volumio[839]: info: ControllerMpd::getState May 02 20:52:49 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand status May 02 20:52:49 volumio volumio[839]: verbose: ControllerMpd::parseState May 02 20:52:49 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 20:52:49 volumio volumio[839]: verbose: ControllerMpd::parseState May 02 20:52:49 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 20:52:49 volumio volumio[839]: verbose: ControllerMpd::parseTrackInfo May 02 20:52:49 volumio volumio[839]: info: ControllerMpd::pushState May 02 20:52:49 volumio volumio[839]: info: CoreCommandRouter::servicePushState May 02 20:52:49 volumio volumio[839]: info: CorePlayQueue::getTrack 216 May 02 20:52:49 volumio volumio[839]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":250,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2822 Kbps","isStreaming":false,"title":"阿梨.粤 - 最爱 - 情愿.dff","artist":null,"album":null,"uri":"INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 情愿.dff","trackType":"dff"} May 02 20:52:49 volumio volumio[839]: verbose: CURRENT POSITION 216 May 02 20:52:49 volumio volumio[839]: info: CoreStateMachine::syncState stateService play May 02 20:52:49 volumio volumio[839]: info: CoreStateMachine::syncState currentStatus stop May 02 20:52:49 volumio volumio[839]: info: CoreStateMachine::pushState May 02 20:52:49 volumio volumio[839]: info: CorePlayQueue::getTrack 216 May 02 20:52:49 volumio volumio[839]: info: CoreCommandRouter::volumioPushState May 02 20:52:49 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 20:52:49 volumio volumio[839]: info: ------------------------------ 88ms May 02 20:52:49 volumio volumio[839]: verbose: ControllerMpd::parseTrackInfo May 02 20:52:49 volumio volumio[839]: info: ControllerMpd::pushState May 02 20:52:49 volumio volumio[839]: info: CoreCommandRouter::servicePushState May 02 20:52:49 volumio volumio[839]: info: CorePlayQueue::getTrack 216 May 02 20:52:49 volumio volumio[839]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":250,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2822 Kbps","isStreaming":false,"title":"阿梨.粤 - 最爱 - 情愿.dff","artist":null,"album":null,"uri":"INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 情愿.dff","trackType":"dff"} May 02 20:52:49 volumio volumio[839]: verbose: CURRENT POSITION 216 May 02 20:52:49 volumio volumio[839]: info: CoreStateMachine::syncState stateService play May 02 20:52:49 volumio volumio[839]: info: CoreStateMachine::syncState currentStatus play May 02 20:52:49 volumio volumio[839]: info: Received an update from plugin. extracting info from payload May 02 20:52:49 volumio volumio[839]: info: CoreStateMachine::pushState May 02 20:52:49 volumio volumio[839]: info: CorePlayQueue::getTrack 216 May 02 20:52:49 volumio volumio[839]: info: CoreCommandRouter::volumioPushState May 02 20:52:49 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 20:52:49 volumio volumio[839]: info: CoreStateMachine::pushState May 02 20:52:49 volumio volumio[839]: info: CorePlayQueue::getTrack 216 May 02 20:52:49 volumio volumio[839]: info: CoreCommandRouter::volumioPushState May 02 20:52:49 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 20:52:49 volumio volumio[839]: info: ------------------------------ 94ms May 02 20:52:51 volumio volumio[839]: info: CoreCommandRouter::volumioSeek May 02 20:52:51 volumio volumio[839]: info: CoreStateMachine::seek May 02 20:52:51 volumio volumio[839]: info: CorePlayQueue::getTrack 216 May 02 20:52:51 volumio volumio[839]: info: TRACKBLOCK {"uri":"mnt/INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 情愿.dff","service":"mpd","name":"阿梨.粤 - 最爱 - 情愿.dff","artist":"","album":"","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=994&web=//extralarge&path=%2FINTERNAL%2F%E9%98%BF%E6%A2%A8%C2%B7%E7%B2%A4%20-%20%E6%9C%80%E7%88%B1%5BDSD%5D&metadata=false","duration":250,"samplerate":"2.82 MHz","bitdepth":"1 bit","trackType":"dff","channels":2} May 02 20:52:51 volumio volumio[839]: info: CoreStateMachine::startPlaybackTimer May 02 20:52:51 volumio volumio[839]: info: CorePlayQueue::getTrack 216 May 02 20:52:51 volumio volumio[839]: info: ControllerMpd::seek May 02 20:52:51 volumio volumio[839]: info: CoreStateMachine::pushState May 02 20:52:51 volumio volumio[839]: info: CorePlayQueue::getTrack 216 May 02 20:52:51 volumio volumio[839]: info: CoreCommandRouter::volumioPushState May 02 20:52:51 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 20:52:51 volumio volumio[839]: error: null May 02 20:52:51 volumio volumio[839]: info: May 02 20:52:51 volumio volumio[839]: ---------------------------- MPD announces state update: player May 02 20:52:51 volumio volumio[839]: info: ControllerMpd::getState May 02 20:52:51 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand status May 02 20:52:51 volumio volumio[839]: verbose: ControllerMpd::parseState May 02 20:52:51 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 20:52:51 volumio volumio[839]: verbose: ControllerMpd::parseTrackInfo May 02 20:52:51 volumio volumio[839]: info: ControllerMpd::pushState May 02 20:52:51 volumio volumio[839]: info: CoreCommandRouter::servicePushState May 02 20:52:51 volumio volumio[839]: info: CorePlayQueue::getTrack 216 May 02 20:52:51 volumio volumio[839]: verbose: STATE SERVICE {"status":"play","position":0,"seek":88000,"duration":250,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2822 Kbps","isStreaming":false,"title":"阿梨.粤 - 最爱 - 情愿.dff","artist":null,"album":null,"uri":"INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 情愿.dff","trackType":"dff"} May 02 20:52:51 volumio volumio[839]: verbose: CURRENT POSITION 216 May 02 20:52:51 volumio volumio[839]: info: CoreStateMachine::syncState stateService play May 02 20:52:51 volumio volumio[839]: info: CoreStateMachine::syncState currentStatus play May 02 20:52:51 volumio volumio[839]: info: Received an update from plugin. extracting info from payload May 02 20:52:51 volumio volumio[839]: info: CoreStateMachine::pushState May 02 20:52:51 volumio volumio[839]: info: CorePlayQueue::getTrack 216 May 02 20:52:51 volumio volumio[839]: info: CoreCommandRouter::volumioPushState May 02 20:52:51 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 20:52:51 volumio volumio[839]: info: CoreStateMachine::pushState May 02 20:52:51 volumio volumio[839]: info: CorePlayQueue::getTrack 216 May 02 20:52:51 volumio volumio[839]: info: CoreCommandRouter::volumioPushState May 02 20:52:51 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 20:52:51 volumio volumio[839]: info: ------------------------------ 8ms May 02 20:52:55 volumio volumio[839]: info: CoreCommandRouter::volumioaddPlayList May 02 20:52:55 volumio volumio[839]: info: CoreStateMachine::getQueue May 02 20:52:55 volumio volumio[839]: info: CorePlayQueue::getQueue May 02 20:52:55 volumio volumio[839]: info: CoreStateMachine::addQueueItems May 02 20:52:55 volumio volumio[839]: info: CorePlayQueue::addQueueItems May 02 20:52:55 volumio volumio[839]: info: Adding Item to queue: music-library/INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 一生何求.dff May 02 20:52:55 volumio volumio[839]: info: Exploding uri music-library/INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 一生何求.dff in service mpd May 02 20:52:55 volumio volumio[839]: info: Adding Item to queue: music-library/INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 今宵多珍重.dff May 02 20:52:55 volumio volumio[839]: info: Exploding uri music-library/INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 今宵多珍重.dff in service mpd May 02 20:52:55 volumio volumio[839]: info: Adding Item to queue: music-library/INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 你的浅笑.dff May 02 20:52:55 volumio volumio[839]: info: Exploding uri music-library/INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 你的浅笑.dff in service mpd May 02 20:52:55 volumio volumio[839]: info: Adding Item to queue: music-library/INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 倾城.dff May 02 20:52:55 volumio volumio[839]: info: Exploding uri music-library/INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 倾城.dff in service mpd May 02 20:52:55 volumio volumio[839]: info: Adding Item to queue: music-library/INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 再见二丁目.dff May 02 20:52:55 volumio volumio[839]: info: Exploding uri music-library/INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 再见二丁目.dff in service mpd May 02 20:52:55 volumio volumio[839]: info: Adding Item to queue: music-library/INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 哪儿.dff May 02 20:52:55 volumio volumio[839]: info: Exploding uri music-library/INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 哪儿.dff in service mpd May 02 20:52:55 volumio volumio[839]: info: Adding Item to queue: music-library/INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 夜机.dff May 02 20:52:55 volumio volumio[839]: info: Exploding uri music-library/INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 夜机.dff in service mpd May 02 20:52:55 volumio volumio[839]: info: Adding Item to queue: music-library/INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 情愿.dff May 02 20:52:55 volumio volumio[839]: info: Exploding uri music-library/INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 情愿.dff in service mpd May 02 20:52:55 volumio volumio[839]: info: Adding Item to queue: music-library/INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 最爱.dff May 02 20:52:55 volumio volumio[839]: info: Exploding uri music-library/INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 最爱.dff in service mpd May 02 20:52:55 volumio volumio[839]: info: Adding Item to queue: music-library/INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 月半小夜曲.dff May 02 20:52:55 volumio volumio[839]: info: Exploding uri music-library/INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 月半小夜曲.dff in service mpd May 02 20:52:55 volumio volumio[839]: info: Adding Item to queue: music-library/INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 爱与痛的边缘.dff May 02 20:52:55 volumio volumio[839]: info: Exploding uri music-library/INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 爱与痛的边缘.dff in service mpd May 02 20:52:55 volumio volumio[839]: info: Adding Item to queue: music-library/INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 痴心换情深.dff May 02 20:52:55 volumio volumio[839]: info: Exploding uri music-library/INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 痴心换情深.dff in service mpd May 02 20:52:55 volumio volumio[839]: info: ALBUMART /albumart?cacheid=994&web=//extralarge&path=%2Fmnt%2FINTERNAL%2F%E9%98%BF%E6%A2%A8%C2%B7%E7%B2%A4%20-%20%E6%9C%80%E7%88%B1%5BDSD%5D%2F%E9%98%BF%E6%A2%A8.%E7%B2%A4%20-%20%E6%9C%80%E7%88%B1%20-%20%E4%B8%80%E7%94%9F%E4%BD%95%E6%B1%82.dff&metadata=false May 02 20:52:55 volumio volumio[839]: info: URI /mnt/INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 一生何求.dff May 02 20:52:55 volumio volumio[839]: info: ALBUMART /albumart?cacheid=994&web=//extralarge&path=%2Fmnt%2FINTERNAL%2F%E9%98%BF%E6%A2%A8%C2%B7%E7%B2%A4%20-%20%E6%9C%80%E7%88%B1%5BDSD%5D%2F%E9%98%BF%E6%A2%A8.%E7%B2%A4%20-%20%E6%9C%80%E7%88%B1%20-%20%E4%BB%8A%E5%AE%B5%E5%A4%9A%E7%8F%8D%E9%87%8D.dff&metadata=false May 02 20:52:55 volumio volumio[839]: info: URI /mnt/INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 今宵多珍重.dff May 02 20:52:55 volumio volumio[839]: info: ALBUMART /albumart?cacheid=994&web=//extralarge&path=%2Fmnt%2FINTERNAL%2F%E9%98%BF%E6%A2%A8%C2%B7%E7%B2%A4%20-%20%E6%9C%80%E7%88%B1%5BDSD%5D%2F%E9%98%BF%E6%A2%A8.%E7%B2%A4%20-%20%E6%9C%80%E7%88%B1%20-%20%E4%BD%A0%E7%9A%84%E6%B5%85%E7%AC%91.dff&metadata=false May 02 20:52:55 volumio volumio[839]: info: URI /mnt/INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 你的浅笑.dff May 02 20:52:55 volumio volumio[839]: info: ALBUMART /albumart?cacheid=994&web=//extralarge&path=%2Fmnt%2FINTERNAL%2F%E9%98%BF%E6%A2%A8%C2%B7%E7%B2%A4%20-%20%E6%9C%80%E7%88%B1%5BDSD%5D%2F%E9%98%BF%E6%A2%A8.%E7%B2%A4%20-%20%E6%9C%80%E7%88%B1%20-%20%E5%80%BE%E5%9F%8E.dff&metadata=false May 02 20:52:55 volumio volumio[839]: info: URI /mnt/INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 倾城.dff May 02 20:52:55 volumio volumio[839]: info: ALBUMART /albumart?cacheid=994&web=//extralarge&path=%2Fmnt%2FINTERNAL%2F%E9%98%BF%E6%A2%A8%C2%B7%E7%B2%A4%20-%20%E6%9C%80%E7%88%B1%5BDSD%5D%2F%E9%98%BF%E6%A2%A8.%E7%B2%A4%20-%20%E6%9C%80%E7%88%B1%20-%20%E5%86%8D%E8%A7%81%E4%BA%8C%E4%B8%81%E7%9B%AE.dff&metadata=false May 02 20:52:55 volumio volumio[839]: info: URI /mnt/INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 再见二丁目.dff May 02 20:52:55 volumio volumio[839]: info: ALBUMART /albumart?cacheid=994&web=//extralarge&path=%2Fmnt%2FINTERNAL%2F%E9%98%BF%E6%A2%A8%C2%B7%E7%B2%A4%20-%20%E6%9C%80%E7%88%B1%5BDSD%5D%2F%E9%98%BF%E6%A2%A8.%E7%B2%A4%20-%20%E6%9C%80%E7%88%B1%20-%20%E5%93%AA%E5%84%BF.dff&metadata=false May 02 20:52:55 volumio volumio[839]: info: URI /mnt/INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 哪儿.dff May 02 20:52:55 volumio volumio[839]: info: ALBUMART /albumart?cacheid=994&web=//extralarge&path=%2Fmnt%2FINTERNAL%2F%E9%98%BF%E6%A2%A8%C2%B7%E7%B2%A4%20-%20%E6%9C%80%E7%88%B1%5BDSD%5D%2F%E9%98%BF%E6%A2%A8.%E7%B2%A4%20-%20%E6%9C%80%E7%88%B1%20-%20%E5%A4%9C%E6%9C%BA.dff&metadata=false May 02 20:52:55 volumio volumio[839]: info: URI /mnt/INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 夜机.dff May 02 20:52:55 volumio volumio[839]: info: ALBUMART /albumart?cacheid=994&web=//extralarge&path=%2Fmnt%2FINTERNAL%2F%E9%98%BF%E6%A2%A8%C2%B7%E7%B2%A4%20-%20%E6%9C%80%E7%88%B1%5BDSD%5D%2F%E9%98%BF%E6%A2%A8.%E7%B2%A4%20-%20%E6%9C%80%E7%88%B1%20-%20%E6%83%85%E6%84%BF.dff&metadata=false May 02 20:52:55 volumio volumio[839]: info: URI /mnt/INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 情愿.dff May 02 20:52:55 volumio volumio[839]: info: ALBUMART /albumart?cacheid=994&web=//extralarge&path=%2Fmnt%2FINTERNAL%2F%E9%98%BF%E6%A2%A8%C2%B7%E7%B2%A4%20-%20%E6%9C%80%E7%88%B1%5BDSD%5D%2F%E9%98%BF%E6%A2%A8.%E7%B2%A4%20-%20%E6%9C%80%E7%88%B1%20-%20%E6%9C%80%E7%88%B1.dff&metadata=false May 02 20:52:55 volumio volumio[839]: info: URI /mnt/INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 最爱.dff May 02 20:52:55 volumio volumio[839]: info: ALBUMART /albumart?cacheid=994&web=//extralarge&path=%2Fmnt%2FINTERNAL%2F%E9%98%BF%E6%A2%A8%C2%B7%E7%B2%A4%20-%20%E6%9C%80%E7%88%B1%5BDSD%5D%2F%E9%98%BF%E6%A2%A8.%E7%B2%A4%20-%20%E6%9C%80%E7%88%B1%20-%20%E6%9C%88%E5%8D%8A%E5%B0%8F%E5%A4%9C%E6%9B%B2.dff&metadata=false May 02 20:52:55 volumio volumio[839]: info: URI /mnt/INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 月半小夜曲.dff May 02 20:52:55 volumio volumio[839]: info: ALBUMART /albumart?cacheid=994&web=//extralarge&path=%2Fmnt%2FINTERNAL%2F%E9%98%BF%E6%A2%A8%C2%B7%E7%B2%A4%20-%20%E6%9C%80%E7%88%B1%5BDSD%5D%2F%E9%98%BF%E6%A2%A8.%E7%B2%A4%20-%20%E6%9C%80%E7%88%B1%20-%20%E7%88%B1%E4%B8%8E%E7%97%9B%E7%9A%84%E8%BE%B9%E7%BC%98.dff&metadata=false May 02 20:52:55 volumio volumio[839]: info: URI /mnt/INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 爱与痛的边缘.dff May 02 20:52:55 volumio volumio[839]: info: ALBUMART /albumart?cacheid=994&web=//extralarge&path=%2Fmnt%2FINTERNAL%2F%E9%98%BF%E6%A2%A8%C2%B7%E7%B2%A4%20-%20%E6%9C%80%E7%88%B1%5BDSD%5D%2F%E9%98%BF%E6%A2%A8.%E7%B2%A4%20-%20%E6%9C%80%E7%88%B1%20-%20%E7%97%B4%E5%BF%83%E6%8D%A2%E6%83%85%E6%B7%B1.dff&metadata=false May 02 20:52:55 volumio volumio[839]: info: URI /mnt/INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 痴心换情深.dff May 02 20:52:55 volumio volumio[839]: info: CorePlayQueue::saveQueue May 02 20:52:55 volumio volumio[839]: info: CoreCommandRouter::volumioPushQueue May 02 20:52:55 volumio volumio[839]: info: CoreStateMachine::updateTrackBlock May 02 20:52:55 volumio volumio[839]: info: CorePlayQueue::getTrackBlock May 02 20:52:55 volumio volumio[839]: info: CoreCommandRouter::volumioPlay May 02 20:52:55 volumio volumio[839]: UNSET VOLATILE May 02 20:52:55 volumio volumio[839]: info: CoreStateMachine::play index 218 May 02 20:52:55 volumio volumio[839]: info: CoreStateMachine::setConsumeUpdateService undefined May 02 20:52:55 volumio volumio[839]: info: CoreStateMachine::stop May 02 20:52:55 volumio volumio[839]: info: CoreStateMachine::setConsumeUpdateService undefined May 02 20:52:55 volumio volumio[839]: UNSET VOLATILE May 02 20:52:55 volumio volumio[839]: info: CoreStateMachine::stPlaybackTimer May 02 20:52:55 volumio volumio[839]: info: CoreStateMachine::updateTrackBlock May 02 20:52:55 volumio volumio[839]: info: CorePlayQueue::getTrackBlock May 02 20:52:55 volumio volumio[839]: info: CoreStateMachine::pushState May 02 20:52:55 volumio volumio[839]: info: CorePlayQueue::getTrack 216 May 02 20:52:55 volumio volumio[839]: info: CoreCommandRouter::volumioPushState May 02 20:52:55 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 20:52:55 volumio volumio[839]: info: CoreStateMachine::serviceStop May 02 20:52:55 volumio volumio[839]: info: CorePlayQueue::getTrack 216 May 02 20:52:55 volumio volumio[839]: info: CoreCommandRouter::serviceStop May 02 20:52:55 volumio volumio[839]: info: ControllerMpd::stop May 02 20:52:55 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand stop May 02 20:52:55 volumio volumio[839]: info: May 02 20:52:55 volumio volumio[839]: ---------------------------- MPD announces state update: player May 02 20:52:55 volumio volumio[839]: info: ControllerMpd::getState May 02 20:52:55 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand status May 02 20:52:55 volumio volumio[839]: info: CoreStateMachine::play index undefined May 02 20:52:55 volumio volumio[839]: info: CoreStateMachine::setConsumeUpdateService undefined May 02 20:52:55 volumio volumio[839]: info: CorePlayQueue::getTrack 218 May 02 20:52:55 volumio volumio[839]: info: CoreStateMachine::startPlaybackTimer May 02 20:52:55 volumio volumio[839]: info: CorePlayQueue::getTrack 218 May 02 20:52:55 volumio volumio[839]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 月半小夜曲.dff May 02 20:52:55 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand stop May 02 20:52:55 volumio volumio[839]: verbose: ControllerMpd::parseState May 02 20:52:55 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 20:52:56 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand clear May 02 20:52:56 volumio volumio[839]: info: May 02 20:52:56 volumio volumio[839]: ---------------------------- MPD announces system playlist update May 02 20:52:56 volumio volumio[839]: info: Ignoring MPD Status Update May 02 20:52:56 volumio volumio[839]: verbose: ControllerMpd::parseTrackInfo May 02 20:52:56 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 月半小夜曲.dff" May 02 20:52:56 volumio volumio[839]: info: ControllerMpd::pushState May 02 20:52:56 volumio volumio[839]: info: CoreCommandRouter::servicePushState May 02 20:52:56 volumio volumio[839]: info: CoreStateMachine::pushState May 02 20:52:56 volumio volumio[839]: info: CorePlayQueue::getTrack 218 May 02 20:52:56 volumio volumio[839]: info: CoreCommandRouter::volumioPushState May 02 20:52:56 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 20:52:56 volumio volumio[839]: info: CorePlayQueue::getTrack 218 May 02 20:52:56 volumio volumio[839]: 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":"阿梨.粤 - 最爱 - 情愿.dff","artist":null,"album":null,"uri":"INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 情愿.dff","trackType":"dff"} May 02 20:52:56 volumio volumio[839]: verbose: CURRENT POSITION 218 May 02 20:52:56 volumio volumio[839]: info: CoreStateMachine::syncState stateService stop May 02 20:52:56 volumio volumio[839]: info: CoreStateMachine::syncState currentStatus stop May 02 20:52:56 volumio volumio[839]: info: CoreStateMachine::pushState May 02 20:52:56 volumio volumio[839]: info: CorePlayQueue::getTrack 218 May 02 20:52:56 volumio volumio[839]: info: CoreCommandRouter::volumioPushState May 02 20:52:56 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 20:52:56 volumio volumio[839]: info: No code May 02 20:52:56 volumio volumio[839]: info: CoreStateMachine::pushState May 02 20:52:56 volumio volumio[839]: info: CorePlayQueue::getTrack 218 May 02 20:52:56 volumio volumio[839]: info: CoreCommandRouter::volumioPushState May 02 20:52:56 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 20:52:56 volumio volumio[839]: info: ------------------------------ 13ms May 02 20:52:56 volumio volumio[839]: error: updateQueue error: null May 02 20:52:56 volumio volumio[839]: info: May 02 20:52:56 volumio volumio[839]: ---------------------------- MPD announces system playlist update May 02 20:52:56 volumio volumio[839]: info: Ignoring MPD Status Update May 02 20:52:56 volumio volumio[839]: info: ------------------------------ 10ms May 02 20:52:56 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand play May 02 20:52:56 volumio volumio[839]: info: ------------------------------ 6ms May 02 20:52:56 volumio volumio[839]: info: May 02 20:52:56 volumio volumio[839]: ---------------------------- MPD announces state update: player May 02 20:52:56 volumio volumio[839]: info: ControllerMpd::getState May 02 20:52:56 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand status May 02 20:52:56 volumio volumio[839]: info: May 02 20:52:56 volumio volumio[839]: ---------------------------- MPD announces state update: player May 02 20:52:56 volumio volumio[839]: info: ControllerMpd::getState May 02 20:52:56 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand status May 02 20:52:56 volumio volumio[839]: verbose: ControllerMpd::parseState May 02 20:52:56 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 20:52:56 volumio volumio[839]: verbose: ControllerMpd::parseState May 02 20:52:56 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 20:52:56 volumio volumio[839]: verbose: ControllerMpd::parseTrackInfo May 02 20:52:56 volumio volumio[839]: info: ControllerMpd::pushState May 02 20:52:56 volumio volumio[839]: info: CoreCommandRouter::servicePushState May 02 20:52:56 volumio volumio[839]: info: CorePlayQueue::getTrack 218 May 02 20:52:56 volumio volumio[839]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":307,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"阿梨.粤 - 最爱 - 月半小夜曲.dff","artist":null,"album":null,"uri":"INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 月半小夜曲.dff","trackType":"dff"} May 02 20:52:56 volumio volumio[839]: verbose: CURRENT POSITION 218 May 02 20:52:56 volumio volumio[839]: info: CoreStateMachine::syncState stateService play May 02 20:52:56 volumio volumio[839]: info: CoreStateMachine::syncState currentStatus stop May 02 20:52:56 volumio volumio[839]: info: CoreStateMachine::pushState May 02 20:52:56 volumio volumio[839]: info: CorePlayQueue::getTrack 218 May 02 20:52:56 volumio volumio[839]: info: CoreCommandRouter::volumioPushState May 02 20:52:56 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 20:52:56 volumio volumio[839]: info: ------------------------------ 38ms May 02 20:52:56 volumio volumio[839]: verbose: ControllerMpd::parseTrackInfo May 02 20:52:56 volumio volumio[839]: info: ControllerMpd::pushState May 02 20:52:56 volumio volumio[839]: info: CoreCommandRouter::servicePushState May 02 20:52:56 volumio volumio[839]: info: CorePlayQueue::getTrack 218 May 02 20:52:56 volumio volumio[839]: verbose: STATE SERVICE {"status":"play","position":0,"seek":365,"duration":307,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2822 Kbps","isStreaming":false,"title":"阿梨.粤 - 最爱 - 月半小夜曲.dff","artist":null,"album":null,"uri":"INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 月半小夜曲.dff","trackType":"dff"} May 02 20:52:56 volumio volumio[839]: verbose: CURRENT POSITION 218 May 02 20:52:56 volumio volumio[839]: info: CoreStateMachine::syncState stateService play May 02 20:52:56 volumio volumio[839]: info: CoreStateMachine::syncState currentStatus play May 02 20:52:56 volumio volumio[839]: info: Received an update from plugin. extracting info from payload May 02 20:52:56 volumio volumio[839]: info: CoreStateMachine::pushState May 02 20:52:56 volumio volumio[839]: info: CorePlayQueue::getTrack 218 May 02 20:52:56 volumio volumio[839]: info: CoreCommandRouter::volumioPushState May 02 20:52:56 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 20:52:56 volumio volumio[839]: info: CoreStateMachine::pushState May 02 20:52:56 volumio volumio[839]: info: CorePlayQueue::getTrack 218 May 02 20:52:56 volumio volumio[839]: info: CoreCommandRouter::volumioPushState May 02 20:52:56 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 20:52:56 volumio volumio[839]: info: ------------------------------ 26ms May 02 20:52:58 volumio volumio[839]: info: CoreCommandRouter::volumioSeek May 02 20:52:58 volumio volumio[839]: info: CoreStateMachine::seek May 02 20:52:58 volumio volumio[839]: info: CorePlayQueue::getTrack 218 May 02 20:52:58 volumio volumio[839]: info: TRACKBLOCK {"uri":"mnt/INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 月半小夜曲.dff","service":"mpd","name":"阿梨.粤 - 最爱 - 月半小夜曲.dff","artist":"","album":"","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=994&web=//extralarge&path=%2FINTERNAL%2F%E9%98%BF%E6%A2%A8%C2%B7%E7%B2%A4%20-%20%E6%9C%80%E7%88%B1%5BDSD%5D&metadata=false","duration":307,"samplerate":"2.82 MHz","bitdepth":"1 bit","trackType":"dff","channels":2} May 02 20:52:58 volumio volumio[839]: info: CoreStateMachine::startPlaybackTimer May 02 20:52:58 volumio volumio[839]: info: CorePlayQueue::getTrack 218 May 02 20:52:58 volumio volumio[839]: info: ControllerMpd::seek May 02 20:52:58 volumio volumio[839]: info: CoreStateMachine::pushState May 02 20:52:58 volumio volumio[839]: info: CorePlayQueue::getTrack 218 May 02 20:52:58 volumio volumio[839]: info: CoreCommandRouter::volumioPushState May 02 20:52:58 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 20:52:58 volumio volumio[839]: error: null May 02 20:52:58 volumio volumio[839]: info: May 02 20:52:58 volumio volumio[839]: ---------------------------- MPD announces state update: player May 02 20:52:58 volumio volumio[839]: info: ControllerMpd::getState May 02 20:52:58 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand status May 02 20:52:58 volumio volumio[839]: verbose: ControllerMpd::parseState May 02 20:52:58 volumio volumio[839]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 20:52:58 volumio volumio[839]: verbose: ControllerMpd::parseTrackInfo May 02 20:52:58 volumio volumio[839]: info: ControllerMpd::pushState May 02 20:52:58 volumio volumio[839]: info: CoreCommandRouter::servicePushState May 02 20:52:58 volumio volumio[839]: info: CorePlayQueue::getTrack 218 May 02 20:52:58 volumio volumio[839]: verbose: STATE SERVICE {"status":"play","position":0,"seek":72000,"duration":307,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2822 Kbps","isStreaming":false,"title":"阿梨.粤 - 最爱 - 月半小夜曲.dff","artist":null,"album":null,"uri":"INTERNAL/阿梨·粤 - 最爱[DSD]/阿梨.粤 - 最爱 - 月半小夜曲.dff","trackType":"dff"} May 02 20:52:58 volumio volumio[839]: verbose: CURRENT POSITION 218 May 02 20:52:58 volumio volumio[839]: info: CoreStateMachine::syncState stateService play May 02 20:52:58 volumio volumio[839]: info: CoreStateMachine::syncState currentStatus play May 02 20:52:58 volumio volumio[839]: info: Received an update from plugin. extracting info from payload May 02 20:52:58 volumio volumio[839]: info: CoreStateMachine::pushState May 02 20:52:58 volumio volumio[839]: info: CorePlayQueue::getTrack 218 May 02 20:52:58 volumio volumio[839]: info: CoreCommandRouter::volumioPushState May 02 20:52:58 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 20:52:58 volumio volumio[839]: info: CoreStateMachine::pushState May 02 20:52:58 volumio volumio[839]: info: CorePlayQueue::getTrack 218 May 02 20:52:58 volumio volumio[839]: info: CoreCommandRouter::volumioPushState May 02 20:52:58 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 20:52:58 volumio volumio[839]: info: ------------------------------ 14ms May 02 20:52:59 volumio volumio[839]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Aaron%20Carter/0d68c392-4e3c-4795-8363-20fb032b04ec.jpg' May 02 20:52:59 volumio volumio[839]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Beyond/a2275a2f-8477-43c6-98f1-2c92cf99f550.jpg' May 02 20:52:59 volumio volumio[839]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Allman%20Brown/47527ba5-3f19-4774-8066-cc8d6fc8f0f7.jpg' May 02 20:53:01 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 02 20:53:01 volumio volumio[839]: info: CURURI: artists:// May 02 20:53:03 volumio volumio[839]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/A-Lin/2ab406a2-9f2d-4138-a771-d40b6efd5963.jpg' May 02 20:53:03 volumio volumio[839]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/AGA/4913a5dc-6900-4158-9a3a-dcdee09bc630.jpg' May 02 20:53:03 volumio volumio[839]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/C%20AllStar/279eca61-6161-4bcc-9a18-ae74fb240643.jpg' May 02 20:53:07 volumio volumio[839]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Avril%20Lavigne/e584788f-5965-4013-a535-3a03c378ef68.jpg' May 02 20:53:08 volumio volumio[839]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 02 20:53:08 volumio volumio[839]: { Error: connect ETIMEDOUT 192.133.77.197:80 May 02 20:53:08 volumio volumio[839]: at Object._errnoException (util.js:1022:11) May 02 20:53:08 volumio volumio[839]: at _exceptionWithHostPort (util.js:1044:20) May 02 20:53:08 volumio volumio[839]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) May 02 20:53:08 volumio volumio[839]: code: 'ETIMEDOUT', May 02 20:53:08 volumio volumio[839]: errno: 'ETIMEDOUT', May 02 20:53:08 volumio volumio[839]: syscall: 'connect', May 02 20:53:08 volumio volumio[839]: address: '192.133.77.197', May 02 20:53:08 volumio volumio[839]: port: 80 } May 02 20:53:08 volumio volumio[839]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 02 20:53:08 volumio sudo[2864]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-02 20:52 May 02 20:53:08 volumio sudo[2864]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 8 (jessie)" NAME="Debian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=debian HOME_URL="http://www.debian.org/" SUPPORT_URL="http://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="79c2e7e29d04526018e922852ec460ffc0707dd1" VOLUMIO_FE_VERSION="77c86e8a1bfb19b724ead35c47d417c4832cf290" VOLUMIO_BE_VERSION="f30dbcf35403287bd1b5d6a60fa82e3da7871c6c" VOLUMIO_ARCH="x86" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Jul 16 18:57:30 CEST 2020" VOLUMIO_VERSION="2.799" VOLUMIO_HARDWARE="x86" VOLUMIO_HASH="5c4ecf8094cc23791be364fa44700bc6"