-- Logs begin at Thu 2019-02-14 18:11:59 CST, end at Tue 2025-12-23 09:23:35 CST. -- Dec 23 09:22:05 smsl-n100 volumio[833]: info: CALLMETHOD: music_service mpd savePlaybackOptions [object Object] Dec 23 09:22:05 smsl-n100 volumio[833]: info: CoreCommandRouter::executeOnPlugin: mpd , savePlaybackOptions Dec 23 09:22:05 smsl-n100 sudo[4323]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 23 09:22:05 smsl-n100 sudo[4323]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 09:22:05 smsl-n100 volumio[833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 09:22:05 smsl-n100 volumio[833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 09:22:05 smsl-n100 volumio[833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 09:22:05 smsl-n100 volumio[833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 09:22:05 smsl-n100 volumio[833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 09:22:05 smsl-n100 sudo[4323]: pam_unix(sudo:session): session closed for user root Dec 23 09:22:05 smsl-n100 volumio[833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 09:22:05 smsl-n100 volumio[833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 09:22:05 smsl-n100 volumio[833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 09:22:05 smsl-n100 sudo[4326]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 23 09:22:05 smsl-n100 sudo[4326]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 09:22:05 smsl-n100 volumio[833]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 23 09:22:05 smsl-n100 volumio[833]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 23 09:22:05 smsl-n100 systemd[1]: Stopping Music Player Daemon... Dec 23 09:22:05 smsl-n100 volumio[833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 09:22:05 smsl-n100 systemd[1]: mpd.service: Succeeded. Dec 23 09:22:05 smsl-n100 systemd[1]: Stopped Music Player Daemon. Dec 23 09:22:05 smsl-n100 volumio[833]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 23 09:22:05 smsl-n100 volumio[833]: info: MPD Permissions set Dec 23 09:22:05 smsl-n100 systemd[1]: Starting Music Player Daemon... Dec 23 09:22:05 smsl-n100 volumio[833]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 23 09:22:05 smsl-n100 volumio[833]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 23 09:22:05 smsl-n100 sudo[4335]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 23 09:22:05 smsl-n100 sudo[4335]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 09:22:05 smsl-n100 sudo[4335]: pam_unix(sudo:session): session closed for user root Dec 23 09:22:08 smsl-n100 mpd[4339]: Dec 23 09:22 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 23 09:22:08 smsl-n100 systemd[1]: Started Music Player Daemon. Dec 23 09:22:08 smsl-n100 sudo[4326]: pam_unix(sudo:session): session closed for user root Dec 23 09:22:08 smsl-n100 volumio[833]: error: updateQueue error: null Dec 23 09:22:17 smsl-n100 volumio[833]: error: Upnp client error: Error: This socket has been ended by the other party Dec 23 09:22:19 smsl-n100 volumio[833]: info: Clearing queue after UPNP request Dec 23 09:22:20 smsl-n100 volumio[833]: info: CoreStateMachine::ClearQueue Dec 23 09:22:20 smsl-n100 volumio[833]: info: CoreStateMachine::stop Dec 23 09:22:20 smsl-n100 volumio[833]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 23 09:22:20 smsl-n100 volumio[833]: info: CoreStateMachine::stPlaybackTimer Dec 23 09:22:20 smsl-n100 volumio[833]: info: CoreStateMachine::updateTrackBlock Dec 23 09:22:20 smsl-n100 volumio[833]: info: CorePlayQueue::getTrackBlock Dec 23 09:22:20 smsl-n100 volumio[833]: info: CoreStateMachine::pushState Dec 23 09:22:20 smsl-n100 volumio[833]: info: CorePlayQueue::getTrack 0 Dec 23 09:22:20 smsl-n100 volumio[833]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 09:22:20 smsl-n100 volumio[833]: info: CoreCommandRouter::volumioPushState Dec 23 09:22:20 smsl-n100 volumio[833]: info: MRS: Pushing multiroomSync output update for this device Dec 23 09:22:20 smsl-n100 volumio[833]: info: MRS: Pushing multiroomSync output Dec 23 09:22:20 smsl-n100 volumio[833]: info: CoreStateMachine::serviceStop Dec 23 09:22:20 smsl-n100 volumio[833]: info: CorePlayQueue::getTrack 0 Dec 23 09:22:20 smsl-n100 volumio[833]: info: ControllerMpd::stop Dec 23 09:22:20 smsl-n100 volumio[833]: verbose: ControllerMpd::sendMpdCommand stop Dec 23 09:22:20 smsl-n100 volumio[833]: info: CorePlayQueue::clearPlayQueue Dec 23 09:22:20 smsl-n100 volumio[833]: info: CorePlayQueue::saveQueue Dec 23 09:22:20 smsl-n100 volumio[833]: info: CoreCommandRouter::volumioPushState Dec 23 09:22:20 smsl-n100 volumio[833]: info: MRS: Pushing multiroomSync output update for this device Dec 23 09:22:20 smsl-n100 volumio[833]: info: MRS: Pushing multiroomSync output Dec 23 09:22:20 smsl-n100 volumio[833]: info: CoreCommandRouter::volumioPushQueue Dec 23 09:22:20 smsl-n100 volumio[833]: info: Dec 23 09:22:20 smsl-n100 volumio[833]: ---------------------------- MPD announces system playlist update Dec 23 09:22:20 smsl-n100 volumio[833]: info: Ignoring MPD Status Update Dec 23 09:22:20 smsl-n100 volumio[833]: info: sendMpdCommand stop took 71 milliseconds Dec 23 09:22:20 smsl-n100 volumio[833]: error: updateQueue error: null Dec 23 09:22:20 smsl-n100 volumio[833]: info: ------------------------------ 9ms Dec 23 09:22:20 smsl-n100 volumio[833]: info: Starting UPNP Playback Dec 23 09:22:20 smsl-n100 volumio[833]: info: Preparing playback through UPNP Dec 23 09:22:20 smsl-n100 volumio[833]: info: CoreCommandRouter::volumioGetState Dec 23 09:22:20 smsl-n100 volumio[833]: info: CorePlayQueue::getTrack 0 Dec 23 09:22:20 smsl-n100 volumio[833]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 23 09:22:20 smsl-n100 volumio[833]: info: Dec 23 09:22:20 smsl-n100 volumio[833]: ---------------------------- MPD announces system playlist update Dec 23 09:22:20 smsl-n100 volumio[833]: info: Ignoring MPD Status Update Dec 23 09:22:20 smsl-n100 volumio[833]: info: ------------------------------ 5ms Dec 23 09:22:20 smsl-n100 volumio[833]: info: Dec 23 09:22:20 smsl-n100 volumio[833]: ---------------------------- MPD announces system playlist update Dec 23 09:22:20 smsl-n100 volumio[833]: info: Ignoring MPD Status Update Dec 23 09:22:20 smsl-n100 volumio[833]: info: ------------------------------ 5ms Dec 23 09:22:20 smsl-n100 volumio[833]: info: Dec 23 09:22:20 smsl-n100 volumio[833]: ---------------------------- MPD announces system playlist update Dec 23 09:22:20 smsl-n100 volumio[833]: info: Ignoring MPD Status Update Dec 23 09:22:20 smsl-n100 volumio[833]: info: Dec 23 09:22:20 smsl-n100 volumio[833]: ---------------------------- MPD announces system playlist update Dec 23 09:22:20 smsl-n100 volumio[833]: info: Ignoring MPD Status Update Dec 23 09:22:20 smsl-n100 volumio[833]: info: ------------------------------ 11ms Dec 23 09:22:20 smsl-n100 volumio[833]: info: ------------------------------ 6ms Dec 23 09:22:20 smsl-n100 volumio[833]: info: Dec 23 09:22:20 smsl-n100 volumio[833]: ---------------------------- MPD announces system playlist update Dec 23 09:22:20 smsl-n100 volumio[833]: info: Ignoring MPD Status Update Dec 23 09:22:20 smsl-n100 volumio[833]: info: Dec 23 09:22:20 smsl-n100 volumio[833]: ---------------------------- MPD announces system playlist update Dec 23 09:22:20 smsl-n100 volumio[833]: info: Ignoring MPD Status Update Dec 23 09:22:20 smsl-n100 volumio[833]: info: ------------------------------ 8ms Dec 23 09:22:20 smsl-n100 volumio[833]: info: ------------------------------ 14ms Dec 23 09:22:21 smsl-n100 volumio[833]: info: Dec 23 09:22:21 smsl-n100 volumio[833]: ---------------------------- MPD announces state update: player Dec 23 09:22:21 smsl-n100 volumio[833]: info: ControllerMpd::getState Dec 23 09:22:21 smsl-n100 volumio[833]: verbose: ControllerMpd::sendMpdCommand status Dec 23 09:22:21 smsl-n100 volumio[833]: info: Dec 23 09:22:21 smsl-n100 volumio[833]: ---------------------------- MPD announces system playlist update Dec 23 09:22:21 smsl-n100 volumio[833]: info: Ignoring MPD Status Update Dec 23 09:22:21 smsl-n100 volumio[833]: info: Dec 23 09:22:21 smsl-n100 volumio[833]: ---------------------------- MPD announces state update: player Dec 23 09:22:21 smsl-n100 volumio[833]: info: sendMpdCommand status took 86 milliseconds Dec 23 09:22:21 smsl-n100 volumio[833]: info: ControllerMpd::getState Dec 23 09:22:21 smsl-n100 volumio[833]: verbose: ControllerMpd::sendMpdCommand status Dec 23 09:22:21 smsl-n100 volumio[833]: verbose: ControllerMpd::parseState Dec 23 09:22:21 smsl-n100 volumio[833]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 09:22:21 smsl-n100 volumio[833]: info: ------------------------------ 25ms Dec 23 09:22:21 smsl-n100 volumio[833]: info: sendMpdCommand status took 14 milliseconds Dec 23 09:22:21 smsl-n100 volumio[833]: info: sendMpdCommand playlistinfo took 12 milliseconds Dec 23 09:22:21 smsl-n100 volumio[833]: verbose: ControllerMpd::parseState Dec 23 09:22:21 smsl-n100 volumio[833]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 09:22:21 smsl-n100 volumio[833]: verbose: ControllerMpd::parseTrackInfo Dec 23 09:22:21 smsl-n100 volumio[833]: info: ControllerMpd::pushState Dec 23 09:22:21 smsl-n100 volumio[833]: info: CoreCommandRouter::servicePushState Dec 23 09:22:21 smsl-n100 volumio[833]: verbose: In UPNP mode Dec 23 09:22:21 smsl-n100 volumio[833]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":176,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"深海回响","artist":"李尧音","album":"深海回响","uri":"http://58.221.45.186/amobile.music.tc.qq.com/F000001H8opO2UxKXb.flac?guid=000000000f3fcffc00000191b677cda7&vkey=5BA82100D26B63D0D63A9AA1C20509E675F511E89B9827BADB92607EB71FF35A8A828A5DB71FC5E5355C24D2C224A2EDA1AF1B0EFBD64E9A__v21ebdd73e&uin=570262251&redirect=1&fromtag=111114","trackType":""} Dec 23 09:22:21 smsl-n100 volumio[833]: verbose: CURRENT POSITION 0 Dec 23 09:22:21 smsl-n100 volumio[833]: info: CoreStateMachine::syncState stateService play Dec 23 09:22:21 smsl-n100 volumio[833]: info: CoreStateMachine::syncState currentStatus stop Dec 23 09:22:21 smsl-n100 volumio[833]: info: ------------------------------ 129ms Dec 23 09:22:21 smsl-n100 volumio[833]: info: sendMpdCommand playlistinfo took 19 milliseconds Dec 23 09:22:21 smsl-n100 volumio[833]: verbose: ControllerMpd::parseTrackInfo Dec 23 09:22:21 smsl-n100 volumio[833]: info: ControllerMpd::pushState Dec 23 09:22:21 smsl-n100 volumio[833]: info: CoreCommandRouter::servicePushState Dec 23 09:22:21 smsl-n100 volumio[833]: verbose: In UPNP mode Dec 23 09:22:21 smsl-n100 volumio[833]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1234,"duration":176,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1239 Kbps","isStreaming":false,"title":"深海回响","artist":"李尧音","album":"深海回响","uri":"http://58.221.45.186/amobile.music.tc.qq.com/F000001H8opO2UxKXb.flac?guid=000000000f3fcffc00000191b677cda7&vkey=5BA82100D26B63D0D63A9AA1C20509E675F511E89B9827BADB92607EB71FF35A8A828A5DB71FC5E5355C24D2C224A2EDA1AF1B0EFBD64E9A__v21ebdd73e&uin=570262251&redirect=1&fromtag=111114","trackType":""} Dec 23 09:22:21 smsl-n100 volumio[833]: verbose: CURRENT POSITION 0 Dec 23 09:22:21 smsl-n100 volumio[833]: info: CoreStateMachine::syncState stateService play Dec 23 09:22:21 smsl-n100 volumio[833]: info: CoreStateMachine::syncState currentStatus play Dec 23 09:22:21 smsl-n100 volumio[833]: info: Received an update from plugin. extracting info from payload Dec 23 09:22:21 smsl-n100 volumio[833]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 23 09:22:21 smsl-n100 volumio[833]: info: CoreStateMachine::pushState Dec 23 09:22:21 smsl-n100 volumio[833]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 09:22:21 smsl-n100 volumio[833]: info: CoreCommandRouter::volumioPushState Dec 23 09:22:21 smsl-n100 volumio[833]: info: MRS: Pushing multiroomSync output update for this device Dec 23 09:22:21 smsl-n100 volumio[833]: info: MRS: Pushing multiroomSync output Dec 23 09:22:21 smsl-n100 volumio[833]: info: CoreStateMachine::pushState Dec 23 09:22:21 smsl-n100 volumio[833]: info: CoreCommandRouter::volumioPushState Dec 23 09:22:21 smsl-n100 volumio[833]: info: MRS: Pushing multiroomSync output update for this device Dec 23 09:22:21 smsl-n100 volumio[833]: info: MRS: Pushing multiroomSync output Dec 23 09:22:21 smsl-n100 volumio[833]: info: ------------------------------ 167ms Dec 23 09:22:21 smsl-n100 volumio[833]: info: CoreCommandRouter::volumioGetQueue Dec 23 09:22:21 smsl-n100 volumio[833]: info: CoreStateMachine::getQueue Dec 23 09:22:21 smsl-n100 volumio[833]: info: CorePlayQueue::getQueue Dec 23 09:22:21 smsl-n100 volumio[833]: info: CoreCommandRouter::volumioGetQueue Dec 23 09:22:21 smsl-n100 volumio[833]: info: CoreStateMachine::getQueue Dec 23 09:22:21 smsl-n100 volumio[833]: info: CorePlayQueue::getQueue Dec 23 09:22:21 smsl-n100 volumio[833]: info: CoreStateMachine::pushState Dec 23 09:22:21 smsl-n100 volumio[833]: info: CoreCommandRouter::volumioPushState Dec 23 09:22:21 smsl-n100 volumio[833]: info: MRS: Pushing multiroomSync output update for this device Dec 23 09:22:21 smsl-n100 volumio[833]: info: MRS: Pushing multiroomSync output Dec 23 09:22:21 smsl-n100 volumio[833]: error: Serial API: Failed to decode command: 100, message: undefined Dec 23 09:22:21 smsl-n100 volumio[833]: error: Serial API: Failed to decode command: MAXVOL, message: Dec 23 09:22:21 smsl-n100 volumio[833]: error: Serial API: Failed to decode command: 100, message: undefined Dec 23 09:22:21 smsl-n100 volumio[833]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Dec 23 09:22:21 smsl-n100 volumio[833]: info: CoreCommandRouter::volumioGetQueue Dec 23 09:22:21 smsl-n100 volumio[833]: info: CoreStateMachine::getQueue Dec 23 09:22:21 smsl-n100 volumio[833]: info: CorePlayQueue::getQueue Dec 23 09:22:25 smsl-n100 volumio[833]: info: Dec 23 09:22:25 smsl-n100 volumio[833]: ---------------------------- MPD announces state update: player Dec 23 09:22:25 smsl-n100 volumio[833]: info: ControllerMpd::getState Dec 23 09:22:25 smsl-n100 volumio[833]: verbose: ControllerMpd::sendMpdCommand status Dec 23 09:22:25 smsl-n100 volumio[833]: info: sendMpdCommand status took 3 milliseconds Dec 23 09:22:25 smsl-n100 volumio[833]: verbose: ControllerMpd::parseState Dec 23 09:22:25 smsl-n100 volumio[833]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 09:22:25 smsl-n100 volumio[833]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 23 09:22:25 smsl-n100 volumio[833]: verbose: ControllerMpd::parseTrackInfo Dec 23 09:22:25 smsl-n100 volumio[833]: info: ControllerMpd::pushState Dec 23 09:22:25 smsl-n100 volumio[833]: info: CoreCommandRouter::servicePushState Dec 23 09:22:25 smsl-n100 volumio[833]: info: CoreStateMachine::pushState Dec 23 09:22:25 smsl-n100 volumio[833]: info: CoreCommandRouter::volumioPushState Dec 23 09:22:25 smsl-n100 volumio[833]: info: MRS: Pushing multiroomSync output update for this device Dec 23 09:22:25 smsl-n100 volumio[833]: info: MRS: Pushing multiroomSync output Dec 23 09:22:25 smsl-n100 volumio[833]: info: CorePlayQueue::getTrack 0 Dec 23 09:22:25 smsl-n100 volumio[833]: 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://58.221.45.186/amobile.music.tc.qq.com/F000001H8opO2UxKXb.flac?guid=000000000f3fcffc00000191b677cda7&vkey=5BA82100D26B63D0D63A9AA1C20509E675F511E89B9827BADB92607EB71FF35A8A828A5DB71FC5E5355C24D2C224A2EDA1AF1B0EFBD64E9A__v21ebdd73e&uin=570262251&redirect=1&fromtag=111114","trackType":""} Dec 23 09:22:25 smsl-n100 volumio[833]: verbose: CURRENT POSITION 0 Dec 23 09:22:25 smsl-n100 volumio[833]: info: CoreStateMachine::syncState stateService stop Dec 23 09:22:25 smsl-n100 volumio[833]: info: CoreStateMachine::syncState currentStatus play Dec 23 09:22:25 smsl-n100 volumio[833]: info: CoreCommandRouter::volumioPushState Dec 23 09:22:25 smsl-n100 volumio[833]: info: MRS: Pushing multiroomSync output update for this device Dec 23 09:22:25 smsl-n100 volumio[833]: info: MRS: Pushing multiroomSync output Dec 23 09:22:25 smsl-n100 volumio[833]: info: CoreStateMachine::stPlaybackTimer Dec 23 09:22:25 smsl-n100 volumio[833]: info: ------------------------------ 73ms Dec 23 09:22:25 smsl-n100 volumio[833]: info: CoreCommandRouter::volumioGetQueue Dec 23 09:22:25 smsl-n100 volumio[833]: info: CoreStateMachine::getQueue Dec 23 09:22:25 smsl-n100 volumio[833]: info: CorePlayQueue::getQueue Dec 23 09:22:25 smsl-n100 volumio[833]: info: Clearing queue after UPNP request Dec 23 09:22:25 smsl-n100 volumio[833]: info: CoreStateMachine::pushState Dec 23 09:22:25 smsl-n100 volumio[833]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 09:22:25 smsl-n100 volumio[833]: info: CoreCommandRouter::volumioPushState Dec 23 09:22:25 smsl-n100 volumio[833]: info: MRS: Pushing multiroomSync output update for this device Dec 23 09:22:25 smsl-n100 volumio[833]: info: MRS: Pushing multiroomSync output Dec 23 09:22:25 smsl-n100 volumio[833]: error: Serial API: Failed to decode command: 100, message: undefined Dec 23 09:22:25 smsl-n100 volumio[833]: error: Serial API: Failed to decode command: MAXVOL, message: Dec 23 09:22:25 smsl-n100 volumio[833]: error: Serial API: Failed to decode command: 100, message: undefined Dec 23 09:22:25 smsl-n100 volumio[833]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Dec 23 09:22:25 smsl-n100 volumio[833]: info: CoreStateMachine::ClearQueue Dec 23 09:22:25 smsl-n100 volumio[833]: info: CoreStateMachine::stop Dec 23 09:22:25 smsl-n100 volumio[833]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 23 09:22:25 smsl-n100 volumio[833]: info: CorePlayQueue::clearPlayQueue Dec 23 09:22:25 smsl-n100 volumio[833]: info: CorePlayQueue::saveQueue Dec 23 09:22:25 smsl-n100 volumio[833]: info: CoreCommandRouter::volumioPushState Dec 23 09:22:25 smsl-n100 volumio[833]: info: MRS: Pushing multiroomSync output update for this device Dec 23 09:22:25 smsl-n100 volumio[833]: info: MRS: Pushing multiroomSync output Dec 23 09:22:25 smsl-n100 volumio[833]: info: CoreCommandRouter::volumioPushQueue Dec 23 09:22:25 smsl-n100 volumio[833]: info: Dec 23 09:22:25 smsl-n100 volumio[833]: ---------------------------- MPD announces system playlist update Dec 23 09:22:25 smsl-n100 volumio[833]: info: Ignoring MPD Status Update Dec 23 09:22:25 smsl-n100 volumio[833]: error: updateQueue error: null Dec 23 09:22:25 smsl-n100 volumio[833]: info: ------------------------------ 6ms Dec 23 09:22:25 smsl-n100 volumio[833]: info: Starting UPNP Playback Dec 23 09:22:25 smsl-n100 volumio[833]: info: Preparing playback through UPNP Dec 23 09:22:25 smsl-n100 volumio[833]: info: CoreCommandRouter::volumioGetState Dec 23 09:22:25 smsl-n100 volumio[833]: info: CorePlayQueue::getTrack 0 Dec 23 09:22:25 smsl-n100 volumio[833]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 23 09:22:26 smsl-n100 volumio[833]: info: Dec 23 09:22:26 smsl-n100 volumio[833]: ---------------------------- MPD announces system playlist update Dec 23 09:22:26 smsl-n100 volumio[833]: info: Ignoring MPD Status Update Dec 23 09:22:26 smsl-n100 volumio[833]: info: ------------------------------ 5ms Dec 23 09:22:26 smsl-n100 volumio[833]: info: Dec 23 09:22:26 smsl-n100 volumio[833]: ---------------------------- MPD announces system playlist update Dec 23 09:22:26 smsl-n100 volumio[833]: info: Ignoring MPD Status Update Dec 23 09:22:26 smsl-n100 volumio[833]: info: ------------------------------ 5ms Dec 23 09:22:26 smsl-n100 volumio[833]: info: Dec 23 09:22:26 smsl-n100 volumio[833]: ---------------------------- MPD announces system playlist update Dec 23 09:22:26 smsl-n100 volumio[833]: info: Ignoring MPD Status Update Dec 23 09:22:26 smsl-n100 volumio[833]: info: Dec 23 09:22:26 smsl-n100 volumio[833]: ---------------------------- MPD announces system playlist update Dec 23 09:22:26 smsl-n100 volumio[833]: info: Ignoring MPD Status Update Dec 23 09:22:26 smsl-n100 volumio[833]: info: ------------------------------ 7ms Dec 23 09:22:26 smsl-n100 volumio[833]: info: ------------------------------ 6ms Dec 23 09:22:26 smsl-n100 volumio[833]: info: Dec 23 09:22:26 smsl-n100 volumio[833]: ---------------------------- MPD announces system playlist update Dec 23 09:22:26 smsl-n100 volumio[833]: info: Ignoring MPD Status Update Dec 23 09:22:26 smsl-n100 volumio[833]: info: Dec 23 09:22:26 smsl-n100 volumio[833]: ---------------------------- MPD announces system playlist update Dec 23 09:22:26 smsl-n100 volumio[833]: info: Ignoring MPD Status Update Dec 23 09:22:26 smsl-n100 volumio[833]: info: ------------------------------ 9ms Dec 23 09:22:26 smsl-n100 volumio[833]: info: ------------------------------ 8ms Dec 23 09:22:26 smsl-n100 volumio[833]: info: Dec 23 09:22:26 smsl-n100 volumio[833]: ---------------------------- MPD announces state update: player Dec 23 09:22:26 smsl-n100 volumio[833]: info: ControllerMpd::getState Dec 23 09:22:26 smsl-n100 volumio[833]: verbose: ControllerMpd::sendMpdCommand status Dec 23 09:22:26 smsl-n100 volumio[833]: info: Dec 23 09:22:26 smsl-n100 volumio[833]: ---------------------------- MPD announces system playlist update Dec 23 09:22:26 smsl-n100 volumio[833]: info: Ignoring MPD Status Update Dec 23 09:22:26 smsl-n100 volumio[833]: info: Dec 23 09:22:26 smsl-n100 volumio[833]: ---------------------------- MPD announces state update: player Dec 23 09:22:26 smsl-n100 volumio[833]: info: sendMpdCommand status took 51 milliseconds Dec 23 09:22:26 smsl-n100 volumio[833]: info: ControllerMpd::getState Dec 23 09:22:26 smsl-n100 volumio[833]: verbose: ControllerMpd::sendMpdCommand status Dec 23 09:22:26 smsl-n100 volumio[833]: verbose: ControllerMpd::parseState Dec 23 09:22:26 smsl-n100 volumio[833]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 09:22:26 smsl-n100 volumio[833]: info: ------------------------------ 15ms Dec 23 09:22:26 smsl-n100 volumio[833]: info: sendMpdCommand status took 9 milliseconds Dec 23 09:22:26 smsl-n100 volumio[833]: info: sendMpdCommand playlistinfo took 8 milliseconds Dec 23 09:22:26 smsl-n100 volumio[833]: verbose: ControllerMpd::parseState Dec 23 09:22:26 smsl-n100 volumio[833]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 09:22:26 smsl-n100 volumio[833]: verbose: ControllerMpd::parseTrackInfo Dec 23 09:22:26 smsl-n100 volumio[833]: info: ControllerMpd::pushState Dec 23 09:22:26 smsl-n100 volumio[833]: info: CoreCommandRouter::servicePushState Dec 23 09:22:26 smsl-n100 volumio[833]: verbose: In UPNP mode Dec 23 09:22:26 smsl-n100 volumio[833]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":187,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"难却","artist":"平生不晚","album":"难却","uri":"http://58.221.45.186/amobile.music.tc.qq.com/F000004DGfgp1P2fMK.flac?guid=000000000f3fcffc00000191b677cda7&vkey=600F3AB3D804462B425F008DA3E54E4103F72B08216A44D3B8C4CF8342F8F55132D54ACF84EB0E2085C123281E070A88728050D8288AB574__v21e297c91&uin=570262251&redirect=1&fromtag=111114","trackType":""} Dec 23 09:22:26 smsl-n100 volumio[833]: verbose: CURRENT POSITION 0 Dec 23 09:22:26 smsl-n100 volumio[833]: info: CoreStateMachine::syncState stateService play Dec 23 09:22:26 smsl-n100 volumio[833]: info: CoreStateMachine::syncState currentStatus stop Dec 23 09:22:26 smsl-n100 volumio[833]: info: ------------------------------ 92ms Dec 23 09:22:26 smsl-n100 volumio[833]: info: sendMpdCommand playlistinfo took 15 milliseconds Dec 23 09:22:26 smsl-n100 volumio[833]: verbose: ControllerMpd::parseTrackInfo Dec 23 09:22:26 smsl-n100 volumio[833]: info: ControllerMpd::pushState Dec 23 09:22:26 smsl-n100 volumio[833]: info: CoreCommandRouter::servicePushState Dec 23 09:22:26 smsl-n100 volumio[833]: verbose: In UPNP mode Dec 23 09:22:26 smsl-n100 volumio[833]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1238,"duration":187,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1187 Kbps","isStreaming":false,"title":"难却","artist":"平生不晚","album":"难却","uri":"http://58.221.45.186/amobile.music.tc.qq.com/F000004DGfgp1P2fMK.flac?guid=000000000f3fcffc00000191b677cda7&vkey=600F3AB3D804462B425F008DA3E54E4103F72B08216A44D3B8C4CF8342F8F55132D54ACF84EB0E2085C123281E070A88728050D8288AB574__v21e297c91&uin=570262251&redirect=1&fromtag=111114","trackType":""} Dec 23 09:22:26 smsl-n100 volumio[833]: verbose: CURRENT POSITION 0 Dec 23 09:22:26 smsl-n100 volumio[833]: info: CoreStateMachine::syncState stateService play Dec 23 09:22:26 smsl-n100 volumio[833]: info: CoreStateMachine::syncState currentStatus play Dec 23 09:22:26 smsl-n100 volumio[833]: info: Received an update from plugin. extracting info from payload Dec 23 09:22:26 smsl-n100 volumio[833]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 23 09:22:26 smsl-n100 volumio[833]: info: CoreStateMachine::pushState Dec 23 09:22:26 smsl-n100 volumio[833]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 09:22:26 smsl-n100 volumio[833]: info: CoreCommandRouter::volumioPushState Dec 23 09:22:26 smsl-n100 volumio[833]: info: MRS: Pushing multiroomSync output update for this device Dec 23 09:22:26 smsl-n100 volumio[833]: info: MRS: Pushing multiroomSync output Dec 23 09:22:26 smsl-n100 volumio[833]: info: CoreStateMachine::pushState Dec 23 09:22:26 smsl-n100 volumio[833]: info: CoreCommandRouter::volumioPushState Dec 23 09:22:26 smsl-n100 volumio[833]: info: MRS: Pushing multiroomSync output update for this device Dec 23 09:22:26 smsl-n100 volumio[833]: info: MRS: Pushing multiroomSync output Dec 23 09:22:26 smsl-n100 volumio[833]: info: ------------------------------ 231ms Dec 23 09:22:26 smsl-n100 volumio[833]: info: CoreCommandRouter::volumioGetQueue Dec 23 09:22:26 smsl-n100 volumio[833]: info: CoreStateMachine::getQueue Dec 23 09:22:26 smsl-n100 volumio[833]: info: CorePlayQueue::getQueue Dec 23 09:22:26 smsl-n100 volumio[833]: info: CoreCommandRouter::volumioGetQueue Dec 23 09:22:26 smsl-n100 volumio[833]: info: CoreStateMachine::getQueue Dec 23 09:22:26 smsl-n100 volumio[833]: info: CorePlayQueue::getQueue Dec 23 09:22:26 smsl-n100 volumio[833]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%B9%B3%E7%94%9F%E4%B8%8D%E6%99%9A/%E9%9A%BE%E5%8D%B4/12587a6e-af50-4ece-9ef5-2d211178972d.jpg' Dec 23 09:22:27 smsl-n100 volumio[833]: info: Dec 23 09:22:27 smsl-n100 volumio[833]: ---------------------------- MPD announces state update: player Dec 23 09:22:27 smsl-n100 volumio[833]: info: ControllerMpd::getState Dec 23 09:22:27 smsl-n100 volumio[833]: verbose: ControllerMpd::sendMpdCommand status Dec 23 09:22:27 smsl-n100 volumio[833]: info: sendMpdCommand status took 3 milliseconds Dec 23 09:22:27 smsl-n100 volumio[833]: verbose: ControllerMpd::parseState Dec 23 09:22:27 smsl-n100 volumio[833]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 09:22:27 smsl-n100 volumio[833]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 23 09:22:27 smsl-n100 volumio[833]: verbose: ControllerMpd::parseTrackInfo Dec 23 09:22:27 smsl-n100 volumio[833]: info: ControllerMpd::pushState Dec 23 09:22:27 smsl-n100 volumio[833]: info: CoreCommandRouter::servicePushState Dec 23 09:22:27 smsl-n100 volumio[833]: info: CoreStateMachine::pushState Dec 23 09:22:27 smsl-n100 volumio[833]: info: CoreCommandRouter::volumioPushState Dec 23 09:22:27 smsl-n100 volumio[833]: info: MRS: Pushing multiroomSync output update for this device Dec 23 09:22:27 smsl-n100 volumio[833]: info: MRS: Pushing multiroomSync output Dec 23 09:22:27 smsl-n100 volumio[833]: info: CorePlayQueue::getTrack 0 Dec 23 09:22:27 smsl-n100 volumio[833]: 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://58.221.45.186/amobile.music.tc.qq.com/F000004DGfgp1P2fMK.flac?guid=000000000f3fcffc00000191b677cda7&vkey=600F3AB3D804462B425F008DA3E54E4103F72B08216A44D3B8C4CF8342F8F55132D54ACF84EB0E2085C123281E070A88728050D8288AB574__v21e297c91&uin=570262251&redirect=1&fromtag=111114","trackType":""} Dec 23 09:22:27 smsl-n100 volumio[833]: verbose: CURRENT POSITION 0 Dec 23 09:22:27 smsl-n100 volumio[833]: info: CoreStateMachine::syncState stateService stop Dec 23 09:22:27 smsl-n100 volumio[833]: info: CoreStateMachine::syncState currentStatus play Dec 23 09:22:27 smsl-n100 volumio[833]: info: CoreCommandRouter::volumioPushState Dec 23 09:22:27 smsl-n100 volumio[833]: info: MRS: Pushing multiroomSync output update for this device Dec 23 09:22:27 smsl-n100 volumio[833]: info: MRS: Pushing multiroomSync output Dec 23 09:22:27 smsl-n100 volumio[833]: info: CoreStateMachine::stPlaybackTimer Dec 23 09:22:27 smsl-n100 volumio[833]: info: ------------------------------ 68ms Dec 23 09:22:27 smsl-n100 volumio[833]: info: CoreCommandRouter::volumioGetQueue Dec 23 09:22:27 smsl-n100 volumio[833]: info: CoreStateMachine::getQueue Dec 23 09:22:27 smsl-n100 volumio[833]: info: CorePlayQueue::getQueue Dec 23 09:22:27 smsl-n100 volumio[833]: info: Clearing queue after UPNP request Dec 23 09:22:27 smsl-n100 volumio[833]: info: CoreStateMachine::pushState Dec 23 09:22:27 smsl-n100 volumio[833]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 09:22:27 smsl-n100 volumio[833]: info: CoreCommandRouter::volumioPushState Dec 23 09:22:28 smsl-n100 volumio[833]: info: MRS: Pushing multiroomSync output update for this device Dec 23 09:22:28 smsl-n100 volumio[833]: info: MRS: Pushing multiroomSync output Dec 23 09:22:28 smsl-n100 volumio[833]: error: Serial API: Failed to decode command: 100, message: undefined Dec 23 09:22:28 smsl-n100 volumio[833]: error: Serial API: Failed to decode command: MAXVOL, message: Dec 23 09:22:28 smsl-n100 volumio[833]: error: Serial API: Failed to decode command: 100, message: undefined Dec 23 09:22:28 smsl-n100 volumio[833]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Dec 23 09:22:28 smsl-n100 volumio[833]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%B9%B3%E7%94%9F%E4%B8%8D%E6%99%9A/%E9%9A%BE%E5%8D%B4/12587a6e-af50-4ece-9ef5-2d211178972d.jpg' Dec 23 09:22:28 smsl-n100 volumio[833]: info: CoreStateMachine::ClearQueue Dec 23 09:22:28 smsl-n100 volumio[833]: info: CoreStateMachine::stop Dec 23 09:22:28 smsl-n100 volumio[833]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 23 09:22:28 smsl-n100 volumio[833]: info: CorePlayQueue::clearPlayQueue Dec 23 09:22:28 smsl-n100 volumio[833]: info: CorePlayQueue::saveQueue Dec 23 09:22:28 smsl-n100 volumio[833]: info: CoreCommandRouter::volumioPushState Dec 23 09:22:28 smsl-n100 volumio[833]: info: MRS: Pushing multiroomSync output update for this device Dec 23 09:22:28 smsl-n100 volumio[833]: info: MRS: Pushing multiroomSync output Dec 23 09:22:28 smsl-n100 volumio[833]: info: CoreCommandRouter::volumioPushQueue Dec 23 09:22:28 smsl-n100 volumio[833]: info: Dec 23 09:22:28 smsl-n100 volumio[833]: ---------------------------- MPD announces system playlist update Dec 23 09:22:28 smsl-n100 volumio[833]: info: Ignoring MPD Status Update Dec 23 09:22:28 smsl-n100 volumio[833]: error: updateQueue error: null Dec 23 09:22:28 smsl-n100 volumio[833]: info: ------------------------------ 6ms Dec 23 09:22:28 smsl-n100 volumio[833]: info: Starting UPNP Playback Dec 23 09:22:28 smsl-n100 volumio[833]: info: Preparing playback through UPNP Dec 23 09:22:28 smsl-n100 volumio[833]: info: CoreCommandRouter::volumioGetState Dec 23 09:22:28 smsl-n100 volumio[833]: info: CorePlayQueue::getTrack 0 Dec 23 09:22:28 smsl-n100 volumio[833]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 23 09:22:28 smsl-n100 volumio[833]: info: Dec 23 09:22:28 smsl-n100 volumio[833]: ---------------------------- MPD announces system playlist update Dec 23 09:22:28 smsl-n100 volumio[833]: info: Ignoring MPD Status Update Dec 23 09:22:28 smsl-n100 volumio[833]: info: Dec 23 09:22:28 smsl-n100 volumio[833]: ---------------------------- MPD announces system playlist update Dec 23 09:22:28 smsl-n100 volumio[833]: info: Ignoring MPD Status Update Dec 23 09:22:28 smsl-n100 volumio[833]: info: ------------------------------ 8ms Dec 23 09:22:28 smsl-n100 volumio[833]: info: ------------------------------ 5ms Dec 23 09:22:28 smsl-n100 volumio[833]: info: Dec 23 09:22:28 smsl-n100 volumio[833]: ---------------------------- MPD announces system playlist update Dec 23 09:22:28 smsl-n100 volumio[833]: info: Ignoring MPD Status Update Dec 23 09:22:28 smsl-n100 volumio[833]: info: Dec 23 09:22:28 smsl-n100 volumio[833]: ---------------------------- MPD announces system playlist update Dec 23 09:22:28 smsl-n100 volumio[833]: info: Ignoring MPD Status Update Dec 23 09:22:28 smsl-n100 volumio[833]: info: ------------------------------ 7ms Dec 23 09:22:28 smsl-n100 volumio[833]: info: ------------------------------ 5ms Dec 23 09:22:28 smsl-n100 volumio[833]: info: Dec 23 09:22:28 smsl-n100 volumio[833]: ---------------------------- MPD announces system playlist update Dec 23 09:22:28 smsl-n100 volumio[833]: info: Ignoring MPD Status Update Dec 23 09:22:28 smsl-n100 volumio[833]: info: ------------------------------ 5ms Dec 23 09:22:28 smsl-n100 volumio[833]: info: Dec 23 09:22:28 smsl-n100 volumio[833]: ---------------------------- MPD announces system playlist update Dec 23 09:22:28 smsl-n100 volumio[833]: info: Ignoring MPD Status Update Dec 23 09:22:28 smsl-n100 volumio[833]: info: ------------------------------ 5ms Dec 23 09:22:28 smsl-n100 volumio[833]: info: Dec 23 09:22:28 smsl-n100 volumio[833]: ---------------------------- MPD announces state update: player Dec 23 09:22:28 smsl-n100 volumio[833]: info: ControllerMpd::getState Dec 23 09:22:28 smsl-n100 volumio[833]: verbose: ControllerMpd::sendMpdCommand status Dec 23 09:22:28 smsl-n100 volumio[833]: info: Dec 23 09:22:28 smsl-n100 volumio[833]: ---------------------------- MPD announces state update: player Dec 23 09:22:28 smsl-n100 volumio[833]: info: sendMpdCommand status took 18 milliseconds Dec 23 09:22:28 smsl-n100 volumio[833]: info: ControllerMpd::getState Dec 23 09:22:28 smsl-n100 volumio[833]: verbose: ControllerMpd::sendMpdCommand status Dec 23 09:22:28 smsl-n100 volumio[833]: verbose: ControllerMpd::parseState Dec 23 09:22:28 smsl-n100 volumio[833]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 09:22:28 smsl-n100 volumio[833]: info: Dec 23 09:22:28 smsl-n100 volumio[833]: ---------------------------- MPD announces system playlist update Dec 23 09:22:28 smsl-n100 volumio[833]: info: Ignoring MPD Status Update Dec 23 09:22:28 smsl-n100 volumio[833]: info: Dec 23 09:22:28 smsl-n100 volumio[833]: ---------------------------- MPD announces state update: player Dec 23 09:22:28 smsl-n100 volumio[833]: info: ControllerMpd::getState Dec 23 09:22:28 smsl-n100 volumio[833]: verbose: ControllerMpd::sendMpdCommand status Dec 23 09:22:29 smsl-n100 volumio[833]: info: sendMpdCommand status took 24 milliseconds Dec 23 09:22:29 smsl-n100 volumio[833]: info: sendMpdCommand playlistinfo took 23 milliseconds Dec 23 09:22:29 smsl-n100 volumio[833]: info: ------------------------------ 19ms Dec 23 09:22:29 smsl-n100 volumio[833]: info: sendMpdCommand status took 14 milliseconds Dec 23 09:22:29 smsl-n100 volumio[833]: verbose: ControllerMpd::parseState Dec 23 09:22:29 smsl-n100 volumio[833]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 09:22:29 smsl-n100 volumio[833]: verbose: ControllerMpd::parseTrackInfo Dec 23 09:22:29 smsl-n100 volumio[833]: verbose: ControllerMpd::parseState Dec 23 09:22:29 smsl-n100 volumio[833]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 09:22:29 smsl-n100 volumio[833]: info: ControllerMpd::pushState Dec 23 09:22:29 smsl-n100 volumio[833]: info: CoreCommandRouter::servicePushState Dec 23 09:22:29 smsl-n100 volumio[833]: verbose: In UPNP mode Dec 23 09:22:29 smsl-n100 volumio[833]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":204,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"画离弦","artist":"邓寓君(等什么君)","album":"画离弦","uri":"http://58.221.45.186/amobile.music.tc.qq.com/F000001dytsm2T6QKE.flac?guid=000000000f3fcffc00000191b677cda7&vkey=6D3020C7058899C78011739582CDC594D1ECCDF0E01BB26D93EA171E54213362833E5A9BC10D4E5D23B8EFE07DCB18A495260CD8429F9F56__v21ebdc56f&uin=570262251&redirect=1&fromtag=111114","trackType":""} Dec 23 09:22:29 smsl-n100 volumio[833]: verbose: CURRENT POSITION 0 Dec 23 09:22:29 smsl-n100 volumio[833]: info: CoreStateMachine::syncState stateService play Dec 23 09:22:29 smsl-n100 volumio[833]: info: CoreStateMachine::syncState currentStatus stop Dec 23 09:22:29 smsl-n100 volumio[833]: info: ------------------------------ 81ms Dec 23 09:22:29 smsl-n100 volumio[833]: info: sendMpdCommand playlistinfo took 21 milliseconds Dec 23 09:22:29 smsl-n100 volumio[833]: info: sendMpdCommand playlistinfo took 19 milliseconds Dec 23 09:22:29 smsl-n100 volumio[833]: verbose: ControllerMpd::parseTrackInfo Dec 23 09:22:29 smsl-n100 volumio[833]: info: ControllerMpd::pushState Dec 23 09:22:29 smsl-n100 volumio[833]: info: CoreCommandRouter::servicePushState Dec 23 09:22:29 smsl-n100 volumio[833]: verbose: In UPNP mode Dec 23 09:22:29 smsl-n100 volumio[833]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":204,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1288 Kbps","isStreaming":false,"title":"画离弦","artist":"邓寓君(等什么君)","album":"画离弦","uri":"http://58.221.45.186/amobile.music.tc.qq.com/F000001dytsm2T6QKE.flac?guid=000000000f3fcffc00000191b677cda7&vkey=6D3020C7058899C78011739582CDC594D1ECCDF0E01BB26D93EA171E54213362833E5A9BC10D4E5D23B8EFE07DCB18A495260CD8429F9F56__v21ebdc56f&uin=570262251&redirect=1&fromtag=111114","trackType":""} Dec 23 09:22:29 smsl-n100 volumio[833]: verbose: CURRENT POSITION 0 Dec 23 09:22:29 smsl-n100 volumio[833]: info: CoreStateMachine::syncState stateService play Dec 23 09:22:29 smsl-n100 volumio[833]: info: CoreStateMachine::syncState currentStatus play Dec 23 09:22:29 smsl-n100 volumio[833]: info: Received an update from plugin. extracting info from payload Dec 23 09:22:29 smsl-n100 volumio[833]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 23 09:22:29 smsl-n100 volumio[833]: info: CoreStateMachine::pushState Dec 23 09:22:29 smsl-n100 volumio[833]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 09:22:29 smsl-n100 volumio[833]: info: CoreCommandRouter::volumioPushState Dec 23 09:22:29 smsl-n100 volumio[833]: info: MRS: Pushing multiroomSync output update for this device Dec 23 09:22:29 smsl-n100 volumio[833]: info: MRS: Pushing multiroomSync output Dec 23 09:22:29 smsl-n100 volumio[833]: info: CoreStateMachine::pushState Dec 23 09:22:29 smsl-n100 volumio[833]: info: CoreCommandRouter::volumioPushState Dec 23 09:22:29 smsl-n100 volumio[833]: info: MRS: Pushing multiroomSync output update for this device Dec 23 09:22:29 smsl-n100 volumio[833]: info: MRS: Pushing multiroomSync output Dec 23 09:22:29 smsl-n100 volumio[833]: info: ControllerMpd::pushState Dec 23 09:22:29 smsl-n100 volumio[833]: info: CoreCommandRouter::servicePushState Dec 23 09:22:29 smsl-n100 volumio[833]: verbose: In UPNP mode Dec 23 09:22:29 smsl-n100 volumio[833]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1238,"duration":204,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1294 Kbps","isStreaming":false,"title":"画离弦","artist":"邓寓君(等什么君)","album":"画离弦","uri":"http://58.221.45.186/amobile.music.tc.qq.com/F000001dytsm2T6QKE.flac?guid=000000000f3fcffc00000191b677cda7&vkey=6D3020C7058899C78011739582CDC594D1ECCDF0E01BB26D93EA171E54213362833E5A9BC10D4E5D23B8EFE07DCB18A495260CD8429F9F56__v21ebdc56f&uin=570262251&redirect=1&fromtag=111114","trackType":""} Dec 23 09:22:29 smsl-n100 volumio[833]: verbose: CURRENT POSITION 0 Dec 23 09:22:29 smsl-n100 volumio[833]: info: CoreStateMachine::syncState stateService play Dec 23 09:22:29 smsl-n100 volumio[833]: info: CoreStateMachine::syncState currentStatus play Dec 23 09:22:29 smsl-n100 volumio[833]: info: Received an update from plugin. extracting info from payload Dec 23 09:22:29 smsl-n100 volumio[833]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 23 09:22:29 smsl-n100 volumio[833]: info: CoreStateMachine::pushState Dec 23 09:22:29 smsl-n100 volumio[833]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 09:22:29 smsl-n100 volumio[833]: info: CoreCommandRouter::volumioPushState Dec 23 09:22:29 smsl-n100 volumio[833]: info: MRS: Pushing multiroomSync output update for this device Dec 23 09:22:29 smsl-n100 volumio[833]: info: MRS: Pushing multiroomSync output Dec 23 09:22:29 smsl-n100 volumio[833]: info: CoreStateMachine::pushState Dec 23 09:22:29 smsl-n100 volumio[833]: info: CoreCommandRouter::volumioPushState Dec 23 09:22:29 smsl-n100 volumio[833]: info: MRS: Pushing multiroomSync output update for this device Dec 23 09:22:29 smsl-n100 volumio[833]: info: MRS: Pushing multiroomSync output Dec 23 09:22:29 smsl-n100 volumio[833]: info: ------------------------------ 317ms Dec 23 09:22:29 smsl-n100 volumio[833]: info: ------------------------------ 302ms Dec 23 09:22:29 smsl-n100 volumio[833]: info: CoreCommandRouter::volumioGetQueue Dec 23 09:22:29 smsl-n100 volumio[833]: info: CoreStateMachine::getQueue Dec 23 09:22:29 smsl-n100 volumio[833]: info: CorePlayQueue::getQueue Dec 23 09:22:29 smsl-n100 volumio[833]: info: CoreCommandRouter::volumioGetQueue Dec 23 09:22:29 smsl-n100 volumio[833]: info: CoreStateMachine::getQueue Dec 23 09:22:29 smsl-n100 volumio[833]: info: CorePlayQueue::getQueue Dec 23 09:22:29 smsl-n100 volumio[833]: info: CoreCommandRouter::volumioGetQueue Dec 23 09:22:29 smsl-n100 volumio[833]: info: CoreStateMachine::getQueue Dec 23 09:22:29 smsl-n100 volumio[833]: info: CorePlayQueue::getQueue Dec 23 09:22:29 smsl-n100 volumio[833]: info: CoreCommandRouter::volumioGetQueue Dec 23 09:22:29 smsl-n100 volumio[833]: info: CoreStateMachine::getQueue Dec 23 09:22:29 smsl-n100 volumio[833]: info: CorePlayQueue::getQueue Dec 23 09:22:29 smsl-n100 volumio[833]: info: CoreStateMachine::pushState Dec 23 09:22:29 smsl-n100 volumio[833]: info: CoreCommandRouter::volumioPushState Dec 23 09:22:29 smsl-n100 volumio[833]: info: MRS: Pushing multiroomSync output update for this device Dec 23 09:22:29 smsl-n100 volumio[833]: info: MRS: Pushing multiroomSync output Dec 23 09:22:29 smsl-n100 volumio[833]: error: Serial API: Failed to decode command: 100, message: undefined Dec 23 09:22:29 smsl-n100 volumio[833]: error: Serial API: Failed to decode command: MAXVOL, message: Dec 23 09:22:29 smsl-n100 volumio[833]: error: Serial API: Failed to decode command: 100, message: undefined Dec 23 09:22:29 smsl-n100 volumio[833]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Dec 23 09:22:29 smsl-n100 volumio[833]: info: CoreCommandRouter::volumioGetQueue Dec 23 09:22:29 smsl-n100 volumio[833]: info: CoreStateMachine::getQueue Dec 23 09:22:29 smsl-n100 volumio[833]: info: CorePlayQueue::getQueue Dec 23 09:23:34 smsl-n100 volumio[833]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 23 09:23:34 smsl-n100 volumio[833]: Error: connect ETIMEDOUT 108.160.166.148:443 Dec 23 09:23:34 smsl-n100 volumio[833]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Dec 23 09:23:34 smsl-n100 volumio[833]: errno: -110, Dec 23 09:23:34 smsl-n100 volumio[833]: code: 'ETIMEDOUT', Dec 23 09:23:34 smsl-n100 volumio[833]: syscall: 'connect', Dec 23 09:23:34 smsl-n100 volumio[833]: address: '108.160.166.148', Dec 23 09:23:34 smsl-n100 volumio[833]: port: 443 Dec 23 09:23:34 smsl-n100 volumio[833]: } Dec 23 09:23:34 smsl-n100 volumio[833]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 23 09:23:35 smsl-n100 sudo[4606]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-23 09:22 Dec 23 09:23:35 smsl-n100 sudo[4606]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="cc0042a1eab1cb7982711a1347e242f8947b54b6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8205c9d25502d90d2bb411bb156e1d5a8ef7404e" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="smsl" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 20 Jan 2025 07:23:13 PM CET" VOLUMIO_VERSION="3.788" VOLUMIO_HARDWARE="nanopineo3" VOLUMIO_DEVICENAME="Nanopi Neo3" VOLUMIO_VENDOR_MODEL="SMSL S100" VOLUMIO_VENDOR="SMSL" VOLUMIO_MODEL="S100" VOLUMIO_HASH="b3195a7f5841a4bc08e3e196c1a91b34"