-- Logs begin at Thu 2019-02-14 18:11:59 CST, end at Tue 2025-02-18 11:51:04 CST. -- Feb 18 11:50:00 smsl-d400n volumio[1154]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 18 11:50:04 smsl-d400n volumio[1154]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 18 11:50:08 smsl-d400n volumio[1154]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 18 11:50:12 smsl-d400n volumio[1154]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 18 11:50:16 smsl-d400n volumio[1154]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 18 11:50:17 smsl-d400n volumio[1154]: info: CorePlayQueue::getTrack 10 Feb 18 11:50:17 smsl-d400n volumio[1154]: info: CorePlayQueue::getTrack 11 Feb 18 11:50:17 smsl-d400n volumio[1154]: info: Prefetching next song Feb 18 11:50:17 smsl-d400n volumio[1154]: info: DOING PREFETCH IN MPD Feb 18 11:50:17 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand add "USB/ESDUSB/七里香.wav" Feb 18 11:50:17 smsl-d400n volumio[1154]: info: Feb 18 11:50:17 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Feb 18 11:50:17 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Feb 18 11:50:17 smsl-d400n volumio[1154]: info: sendMpdCommand add "USB/ESDUSB/七里香.wav" took 7 milliseconds Feb 18 11:50:17 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand consume 1 Feb 18 11:50:17 smsl-d400n volumio[1154]: info: Feb 18 11:50:17 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Feb 18 11:50:17 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Feb 18 11:50:17 smsl-d400n volumio[1154]: info: Feb 18 11:50:17 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Feb 18 11:50:17 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Feb 18 11:50:17 smsl-d400n volumio[1154]: info: Feb 18 11:50:17 smsl-d400n volumio[1154]: ---------------------------- MPD announces state update: options Feb 18 11:50:17 smsl-d400n volumio[1154]: info: ------------------------------ 23ms Feb 18 11:50:17 smsl-d400n volumio[1154]: info: sendMpdCommand consume 1 took 17 milliseconds Feb 18 11:50:17 smsl-d400n volumio[1154]: info: ControllerMpd::getState Feb 18 11:50:17 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand status Feb 18 11:50:17 smsl-d400n volumio[1154]: info: ------------------------------ 15ms Feb 18 11:50:17 smsl-d400n volumio[1154]: info: ------------------------------ 12ms Feb 18 11:50:17 smsl-d400n volumio[1154]: info: Feb 18 11:50:17 smsl-d400n volumio[1154]: ---------------------------- MPD announces state update: options Feb 18 11:50:17 smsl-d400n volumio[1154]: info: ControllerMpd::getState Feb 18 11:50:17 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand status Feb 18 11:50:17 smsl-d400n volumio[1154]: info: Feb 18 11:50:17 smsl-d400n volumio[1154]: ---------------------------- MPD announces state update: options Feb 18 11:50:17 smsl-d400n volumio[1154]: info: ControllerMpd::getState Feb 18 11:50:17 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand status Feb 18 11:50:17 smsl-d400n volumio[1154]: info: sendMpdCommand status took 19 milliseconds Feb 18 11:50:17 smsl-d400n volumio[1154]: info: sendMpdCommand status took 11 milliseconds Feb 18 11:50:17 smsl-d400n volumio[1154]: info: sendMpdCommand status took 8 milliseconds Feb 18 11:50:17 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseState Feb 18 11:50:17 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 11:50:17 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseState Feb 18 11:50:17 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 11:50:17 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseState Feb 18 11:50:17 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 11:50:17 smsl-d400n volumio[1154]: info: sendMpdCommand playlistinfo took 10 milliseconds Feb 18 11:50:17 smsl-d400n volumio[1154]: info: sendMpdCommand playlistinfo took 10 milliseconds Feb 18 11:50:17 smsl-d400n volumio[1154]: info: sendMpdCommand playlistinfo took 11 milliseconds Feb 18 11:50:17 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseTrackInfo Feb 18 11:50:17 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseTrackInfo Feb 18 11:50:17 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseTrackInfo Feb 18 11:50:17 smsl-d400n volumio[1154]: info: ControllerMpd::pushState Feb 18 11:50:17 smsl-d400n volumio[1154]: info: CoreCommandRouter::servicePushState Feb 18 11:50:17 smsl-d400n volumio[1154]: info: CorePlayQueue::getTrack 10 Feb 18 11:50:17 smsl-d400n volumio[1154]: verbose: STATE SERVICE {"status":"play","position":0,"seek":244382,"duration":249,"samplerate":"384 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1588 Kbps","isStreaming":false,"title":"安和桥 - 宇西.flac","artist":null,"album":null,"uri":"USB/ESDUSB/安和桥 - 宇西.flac","trackType":"flac"} Feb 18 11:50:17 smsl-d400n volumio[1154]: verbose: CURRENT POSITION 10 Feb 18 11:50:17 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState stateService play Feb 18 11:50:17 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState currentStatus play Feb 18 11:50:17 smsl-d400n volumio[1154]: info: Received an update from plugin. extracting info from payload Feb 18 11:50:17 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Feb 18 11:50:17 smsl-d400n volumio[1154]: info: CorePlayQueue::getTrack 10 Feb 18 11:50:17 smsl-d400n volumio[1154]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 11:50:17 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Feb 18 11:50:17 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Feb 18 11:50:17 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Feb 18 11:50:17 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Feb 18 11:50:17 smsl-d400n volumio[1154]: info: CorePlayQueue::getTrack 10 Feb 18 11:50:17 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Feb 18 11:50:17 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Feb 18 11:50:17 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Feb 18 11:50:17 smsl-d400n volumio[1154]: info: ControllerMpd::pushState Feb 18 11:50:17 smsl-d400n volumio[1154]: info: CoreCommandRouter::servicePushState Feb 18 11:50:17 smsl-d400n volumio[1154]: info: CorePlayQueue::getTrack 10 Feb 18 11:50:17 smsl-d400n volumio[1154]: verbose: STATE SERVICE {"status":"play","position":0,"seek":244469,"duration":249,"samplerate":"384 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1588 Kbps","isStreaming":false,"title":"安和桥 - 宇西.flac","artist":null,"album":null,"uri":"USB/ESDUSB/安和桥 - 宇西.flac","trackType":"flac"} Feb 18 11:50:17 smsl-d400n volumio[1154]: verbose: CURRENT POSITION 10 Feb 18 11:50:17 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState stateService play Feb 18 11:50:17 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState currentStatus play Feb 18 11:50:17 smsl-d400n volumio[1154]: info: Received an update from plugin. extracting info from payload Feb 18 11:50:17 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Feb 18 11:50:17 smsl-d400n volumio[1154]: info: CorePlayQueue::getTrack 10 Feb 18 11:50:17 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Feb 18 11:50:17 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Feb 18 11:50:17 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Feb 18 11:50:17 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Feb 18 11:50:17 smsl-d400n volumio[1154]: info: CorePlayQueue::getTrack 10 Feb 18 11:50:17 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Feb 18 11:50:18 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Feb 18 11:50:18 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Feb 18 11:50:18 smsl-d400n volumio[1154]: info: ControllerMpd::pushState Feb 18 11:50:18 smsl-d400n volumio[1154]: info: CoreCommandRouter::servicePushState Feb 18 11:50:18 smsl-d400n volumio[1154]: info: CorePlayQueue::getTrack 10 Feb 18 11:50:18 smsl-d400n volumio[1154]: verbose: STATE SERVICE {"status":"play","position":0,"seek":244469,"duration":249,"samplerate":"384 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1581 Kbps","isStreaming":false,"title":"安和桥 - 宇西.flac","artist":null,"album":null,"uri":"USB/ESDUSB/安和桥 - 宇西.flac","trackType":"flac"} Feb 18 11:50:18 smsl-d400n volumio[1154]: verbose: CURRENT POSITION 10 Feb 18 11:50:18 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState stateService play Feb 18 11:50:18 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState currentStatus play Feb 18 11:50:18 smsl-d400n volumio[1154]: info: Received an update from plugin. extracting info from payload Feb 18 11:50:18 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Feb 18 11:50:18 smsl-d400n volumio[1154]: info: CorePlayQueue::getTrack 10 Feb 18 11:50:18 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Feb 18 11:50:18 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Feb 18 11:50:18 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Feb 18 11:50:18 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Feb 18 11:50:18 smsl-d400n volumio[1154]: info: CorePlayQueue::getTrack 10 Feb 18 11:50:18 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Feb 18 11:50:18 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Feb 18 11:50:18 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Feb 18 11:50:18 smsl-d400n volumio[1154]: info: ------------------------------ 209ms Feb 18 11:50:18 smsl-d400n volumio[1154]: info: ------------------------------ 202ms Feb 18 11:50:18 smsl-d400n volumio[1154]: info: ------------------------------ 198ms Feb 18 11:50:18 smsl-d400n volumio[1154]: error: Serial API: Failed to decode command: GET, message: STATE Feb 18 11:50:20 smsl-d400n volumio[1154]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 18 11:50:22 smsl-d400n volumio[1154]: info: Feb 18 11:50:22 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Feb 18 11:50:22 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Feb 18 11:50:22 smsl-d400n volumio[1154]: info: Feb 18 11:50:22 smsl-d400n volumio[1154]: ---------------------------- MPD announces state update: player Feb 18 11:50:22 smsl-d400n volumio[1154]: info: ControllerMpd::getState Feb 18 11:50:22 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand status Feb 18 11:50:22 smsl-d400n volumio[1154]: info: Feb 18 11:50:22 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Feb 18 11:50:22 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Feb 18 11:50:22 smsl-d400n volumio[1154]: info: Feb 18 11:50:22 smsl-d400n volumio[1154]: ---------------------------- MPD announces state update: player Feb 18 11:50:22 smsl-d400n volumio[1154]: info: ControllerMpd::getState Feb 18 11:50:22 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand status Feb 18 11:50:22 smsl-d400n volumio[1154]: info: Feb 18 11:50:22 smsl-d400n volumio[1154]: ---------------------------- MPD announces system playlist update Feb 18 11:50:22 smsl-d400n volumio[1154]: info: Ignoring MPD Status Update Feb 18 11:50:22 smsl-d400n volumio[1154]: info: Feb 18 11:50:22 smsl-d400n volumio[1154]: ---------------------------- MPD announces state update: player Feb 18 11:50:22 smsl-d400n volumio[1154]: info: ControllerMpd::getState Feb 18 11:50:22 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand status Feb 18 11:50:22 smsl-d400n volumio[1154]: info: ------------------------------ 20ms Feb 18 11:50:22 smsl-d400n volumio[1154]: info: CoreStateMachine::startPlaybackTimer Feb 18 11:50:22 smsl-d400n volumio[1154]: info: CorePlayQueue::getTrack 11 Feb 18 11:50:22 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Feb 18 11:50:22 smsl-d400n volumio[1154]: info: CorePlayQueue::getTrack 11 Feb 18 11:50:22 smsl-d400n volumio[1154]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 11:50:22 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Feb 18 11:50:22 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Feb 18 11:50:22 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Feb 18 11:50:22 smsl-d400n volumio[1154]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%3F%3F%3F/%3F%3F%3F/4308b85a-9464-4085-a59d-597183f6bc39.png' Feb 18 11:50:22 smsl-d400n volumio[1154]: info: sendMpdCommand status took 809 milliseconds Feb 18 11:50:22 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseState Feb 18 11:50:22 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 11:50:22 smsl-d400n volumio[1154]: info: Feb 18 11:50:22 smsl-d400n volumio[1154]: ---------------------------- MPD announces state update: player Feb 18 11:50:22 smsl-d400n volumio[1154]: info: ControllerMpd::getState Feb 18 11:50:22 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand status Feb 18 11:50:22 smsl-d400n volumio[1154]: info: ------------------------------ 826ms Feb 18 11:50:22 smsl-d400n volumio[1154]: info: sendMpdCommand status took 823 milliseconds Feb 18 11:50:22 smsl-d400n volumio[1154]: info: ------------------------------ 822ms Feb 18 11:50:22 smsl-d400n volumio[1154]: info: sendMpdCommand status took 819 milliseconds Feb 18 11:50:22 smsl-d400n volumio[1154]: info: sendMpdCommand playlistinfo took 18 milliseconds Feb 18 11:50:22 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseState Feb 18 11:50:22 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 11:50:22 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseState Feb 18 11:50:22 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 11:50:22 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseTrackInfo Feb 18 11:50:22 smsl-d400n volumio[1154]: info: ControllerMpd::pushState Feb 18 11:50:22 smsl-d400n volumio[1154]: info: CoreCommandRouter::servicePushState Feb 18 11:50:22 smsl-d400n volumio[1154]: info: CorePlayQueue::getTrack 11 Feb 18 11:50:22 smsl-d400n volumio[1154]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":298,"samplerate":"192 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"???","artist":"???","album":"???","uri":"USB/ESDUSB/七里香.wav","trackType":"wav"} Feb 18 11:50:22 smsl-d400n volumio[1154]: verbose: CURRENT POSITION 11 Feb 18 11:50:22 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState stateService play Feb 18 11:50:22 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState currentStatus play Feb 18 11:50:22 smsl-d400n volumio[1154]: info: Received an update from plugin. extracting info from payload Feb 18 11:50:22 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Feb 18 11:50:22 smsl-d400n volumio[1154]: info: CorePlayQueue::getTrack 11 Feb 18 11:50:22 smsl-d400n volumio[1154]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 11:50:22 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Feb 18 11:50:22 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Feb 18 11:50:22 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Feb 18 11:50:22 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Feb 18 11:50:22 smsl-d400n volumio[1154]: info: CorePlayQueue::getTrack 11 Feb 18 11:50:22 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Feb 18 11:50:22 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Feb 18 11:50:22 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Feb 18 11:50:22 smsl-d400n volumio[1154]: info: ------------------------------ 914ms Feb 18 11:50:22 smsl-d400n volumio[1154]: info: Feb 18 11:50:22 smsl-d400n volumio[1154]: ---------------------------- MPD announces state update: player Feb 18 11:50:22 smsl-d400n volumio[1154]: info: ControllerMpd::getState Feb 18 11:50:22 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand status Feb 18 11:50:23 smsl-d400n volumio[1154]: info: Feb 18 11:50:23 smsl-d400n volumio[1154]: ---------------------------- MPD announces state update: player Feb 18 11:50:23 smsl-d400n volumio[1154]: info: ControllerMpd::getState Feb 18 11:50:23 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand status Feb 18 11:50:23 smsl-d400n volumio[1154]: info: sendMpdCommand status took 107 milliseconds Feb 18 11:50:23 smsl-d400n volumio[1154]: info: sendMpdCommand playlistinfo took 99 milliseconds Feb 18 11:50:23 smsl-d400n volumio[1154]: info: sendMpdCommand playlistinfo took 99 milliseconds Feb 18 11:50:23 smsl-d400n volumio[1154]: info: sendMpdCommand status took 23 milliseconds Feb 18 11:50:23 smsl-d400n volumio[1154]: info: sendMpdCommand status took 17 milliseconds Feb 18 11:50:23 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseState Feb 18 11:50:23 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 11:50:23 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseTrackInfo Feb 18 11:50:23 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseTrackInfo Feb 18 11:50:23 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseState Feb 18 11:50:23 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 11:50:23 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseState Feb 18 11:50:23 smsl-d400n volumio[1154]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 11:50:23 smsl-d400n volumio[1154]: info: ControllerMpd::pushState Feb 18 11:50:23 smsl-d400n volumio[1154]: info: CoreCommandRouter::servicePushState Feb 18 11:50:23 smsl-d400n volumio[1154]: info: CorePlayQueue::getTrack 11 Feb 18 11:50:23 smsl-d400n volumio[1154]: verbose: STATE SERVICE {"status":"play","position":0,"seek":430,"duration":298,"samplerate":"192 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"???","artist":"???","album":"???","uri":"USB/ESDUSB/七里香.wav","trackType":"wav"} Feb 18 11:50:23 smsl-d400n volumio[1154]: verbose: CURRENT POSITION 11 Feb 18 11:50:23 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState stateService play Feb 18 11:50:23 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState currentStatus play Feb 18 11:50:23 smsl-d400n volumio[1154]: info: Received an update from plugin. extracting info from payload Feb 18 11:50:23 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Feb 18 11:50:23 smsl-d400n volumio[1154]: info: CorePlayQueue::getTrack 11 Feb 18 11:50:23 smsl-d400n volumio[1154]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 11:50:23 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Feb 18 11:50:23 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Feb 18 11:50:23 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Feb 18 11:50:23 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Feb 18 11:50:23 smsl-d400n volumio[1154]: info: CorePlayQueue::getTrack 11 Feb 18 11:50:23 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Feb 18 11:50:23 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Feb 18 11:50:23 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Feb 18 11:50:23 smsl-d400n volumio[1154]: info: ControllerMpd::pushState Feb 18 11:50:23 smsl-d400n volumio[1154]: info: CoreCommandRouter::servicePushState Feb 18 11:50:23 smsl-d400n volumio[1154]: info: CorePlayQueue::getTrack 11 Feb 18 11:50:23 smsl-d400n volumio[1154]: verbose: STATE SERVICE {"status":"play","position":0,"seek":593,"duration":298,"samplerate":"192 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"???","artist":"???","album":"???","uri":"USB/ESDUSB/七里香.wav","trackType":"wav"} Feb 18 11:50:23 smsl-d400n volumio[1154]: verbose: CURRENT POSITION 11 Feb 18 11:50:23 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState stateService play Feb 18 11:50:23 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState currentStatus play Feb 18 11:50:23 smsl-d400n volumio[1154]: info: Received an update from plugin. extracting info from payload Feb 18 11:50:23 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Feb 18 11:50:23 smsl-d400n volumio[1154]: info: CorePlayQueue::getTrack 11 Feb 18 11:50:23 smsl-d400n volumio[1154]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 11:50:23 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Feb 18 11:50:23 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Feb 18 11:50:23 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Feb 18 11:50:23 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Feb 18 11:50:23 smsl-d400n volumio[1154]: info: CorePlayQueue::getTrack 11 Feb 18 11:50:23 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Feb 18 11:50:23 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Feb 18 11:50:23 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Feb 18 11:50:23 smsl-d400n volumio[1154]: info: ------------------------------ 1108ms Feb 18 11:50:23 smsl-d400n volumio[1154]: info: ------------------------------ 1106ms Feb 18 11:50:23 smsl-d400n volumio[1154]: error: Serial API: Failed to decode command: GET, message: STATE Feb 18 11:50:23 smsl-d400n volumio[1154]: info: sendMpdCommand playlistinfo took 259 milliseconds Feb 18 11:50:23 smsl-d400n volumio[1154]: info: sendMpdCommand playlistinfo took 255 milliseconds Feb 18 11:50:23 smsl-d400n volumio[1154]: info: sendMpdCommand playlistinfo took 254 milliseconds Feb 18 11:50:23 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseTrackInfo Feb 18 11:50:23 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseTrackInfo Feb 18 11:50:23 smsl-d400n volumio[1154]: verbose: ControllerMpd::parseTrackInfo Feb 18 11:50:23 smsl-d400n volumio[1154]: info: ControllerMpd::pushState Feb 18 11:50:23 smsl-d400n volumio[1154]: info: CoreCommandRouter::servicePushState Feb 18 11:50:23 smsl-d400n volumio[1154]: info: CorePlayQueue::getTrack 11 Feb 18 11:50:23 smsl-d400n volumio[1154]: verbose: STATE SERVICE {"status":"play","position":0,"seek":850,"duration":298,"samplerate":"192 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"???","artist":"???","album":"???","uri":"USB/ESDUSB/七里香.wav","trackType":"wav"} Feb 18 11:50:23 smsl-d400n volumio[1154]: verbose: CURRENT POSITION 11 Feb 18 11:50:23 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState stateService play Feb 18 11:50:23 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState currentStatus play Feb 18 11:50:23 smsl-d400n volumio[1154]: info: Received an update from plugin. extracting info from payload Feb 18 11:50:23 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Feb 18 11:50:23 smsl-d400n volumio[1154]: info: CorePlayQueue::getTrack 11 Feb 18 11:50:23 smsl-d400n volumio[1154]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 11:50:23 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Feb 18 11:50:23 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Feb 18 11:50:23 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Feb 18 11:50:23 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Feb 18 11:50:23 smsl-d400n volumio[1154]: info: CorePlayQueue::getTrack 11 Feb 18 11:50:23 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Feb 18 11:50:23 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Feb 18 11:50:23 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Feb 18 11:50:23 smsl-d400n volumio[1154]: info: ControllerMpd::pushState Feb 18 11:50:23 smsl-d400n volumio[1154]: info: CoreCommandRouter::servicePushState Feb 18 11:50:23 smsl-d400n volumio[1154]: info: CorePlayQueue::getTrack 11 Feb 18 11:50:23 smsl-d400n volumio[1154]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1021,"duration":298,"samplerate":"192 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"???","artist":"???","album":"???","uri":"USB/ESDUSB/七里香.wav","trackType":"wav"} Feb 18 11:50:23 smsl-d400n volumio[1154]: verbose: CURRENT POSITION 11 Feb 18 11:50:23 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState stateService play Feb 18 11:50:23 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState currentStatus play Feb 18 11:50:23 smsl-d400n volumio[1154]: info: Received an update from plugin. extracting info from payload Feb 18 11:50:23 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Feb 18 11:50:23 smsl-d400n volumio[1154]: info: CorePlayQueue::getTrack 11 Feb 18 11:50:23 smsl-d400n volumio[1154]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 11:50:23 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Feb 18 11:50:23 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Feb 18 11:50:23 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Feb 18 11:50:23 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Feb 18 11:50:23 smsl-d400n volumio[1154]: info: CorePlayQueue::getTrack 11 Feb 18 11:50:23 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Feb 18 11:50:23 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Feb 18 11:50:23 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Feb 18 11:50:23 smsl-d400n volumio[1154]: info: ControllerMpd::pushState Feb 18 11:50:23 smsl-d400n volumio[1154]: info: CoreCommandRouter::servicePushState Feb 18 11:50:23 smsl-d400n volumio[1154]: info: CorePlayQueue::getTrack 11 Feb 18 11:50:23 smsl-d400n volumio[1154]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1021,"duration":298,"samplerate":"192 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"???","artist":"???","album":"???","uri":"USB/ESDUSB/七里香.wav","trackType":"wav"} Feb 18 11:50:23 smsl-d400n volumio[1154]: verbose: CURRENT POSITION 11 Feb 18 11:50:23 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState stateService play Feb 18 11:50:23 smsl-d400n volumio[1154]: info: CoreStateMachine::syncState currentStatus play Feb 18 11:50:23 smsl-d400n volumio[1154]: info: Received an update from plugin. extracting info from payload Feb 18 11:50:23 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Feb 18 11:50:23 smsl-d400n volumio[1154]: info: CorePlayQueue::getTrack 11 Feb 18 11:50:23 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Feb 18 11:50:23 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Feb 18 11:50:23 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Feb 18 11:50:23 smsl-d400n volumio[1154]: info: CoreStateMachine::pushState Feb 18 11:50:23 smsl-d400n volumio[1154]: info: CorePlayQueue::getTrack 11 Feb 18 11:50:23 smsl-d400n volumio[1154]: info: CoreCommandRouter::volumioPushState Feb 18 11:50:23 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output update for this device Feb 18 11:50:23 smsl-d400n volumio[1154]: info: MRS: Pushing multiroomSync output Feb 18 11:50:23 smsl-d400n volumio[1154]: info: ------------------------------ 554ms Feb 18 11:50:23 smsl-d400n volumio[1154]: info: ------------------------------ 461ms Feb 18 11:50:23 smsl-d400n volumio[1154]: info: ------------------------------ 457ms Feb 18 11:50:23 smsl-d400n volumio[1154]: error: Serial API: Failed to decode command: GET, message: STATE Feb 18 11:50:23 smsl-d400n volumio[1154]: error: Serial API: Failed to decode command: GET, message: STATE Feb 18 11:50:24 smsl-d400n volumio[1154]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 18 11:50:28 smsl-d400n volumio[1154]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 18 11:50:32 smsl-d400n volumio[1154]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 18 11:50:36 smsl-d400n volumio[1154]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 18 11:50:40 smsl-d400n volumio[1154]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 18 11:50:45 smsl-d400n volumio[1154]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 18 11:50:49 smsl-d400n volumio[1154]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 18 11:50:52 smsl-d400n volumiologrotate[567]: ls: cannot access '/var/log/samba/log.wb-SMSL': No such file or directory Feb 18 11:50:52 smsl-d400n volumiologrotate[567]: ls: cannot access 'D400N': No such file or directory Feb 18 11:50:53 smsl-d400n volumio[1154]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 18 11:50:57 smsl-d400n volumio[1154]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 18 11:51:01 smsl-d400n volumio[1154]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 18 11:51:03 smsl-d400n volumio[1154]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 18 11:51:03 smsl-d400n volumio[1154]: Error: connect ETIMEDOUT 108.160.162.109:80 Feb 18 11:51:03 smsl-d400n volumio[1154]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Feb 18 11:51:03 smsl-d400n volumio[1154]: errno: -110, Feb 18 11:51:03 smsl-d400n volumio[1154]: code: 'ETIMEDOUT', Feb 18 11:51:03 smsl-d400n volumio[1154]: syscall: 'connect', Feb 18 11:51:03 smsl-d400n volumio[1154]: address: '108.160.162.109', Feb 18 11:51:03 smsl-d400n volumio[1154]: port: 80 Feb 18 11:51:03 smsl-d400n volumio[1154]: } Feb 18 11:51:03 smsl-d400n volumio[1154]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 18 11:51:04 smsl-d400n sudo[7194]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-18 11:50 Feb 18 11:51:04 smsl-d400n sudo[7194]: 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"