Apr 15 08:11:00 volumio volumio[14771]: info: CoreCommandRouter::volumioPlay Apr 15 08:11:00 volumio volumio[14771]: info: CoreStateMachine::play index 14 Apr 15 08:11:00 volumio volumio[14771]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 15 08:11:00 volumio volumio[14771]: info: CoreStateMachine::stop Apr 15 08:11:00 volumio volumio[14771]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 15 08:11:00 volumio volumio[14771]: info: CoreStateMachine::stPlaybackTimer Apr 15 08:11:00 volumio volumio[14771]: info: CoreStateMachine::updateTrackBlock Apr 15 08:11:00 volumio volumio[14771]: info: CorePlayQueue::getTrackBlock Apr 15 08:11:00 volumio volumio[14771]: info: CoreStateMachine::pushState Apr 15 08:11:00 volumio volumio[14771]: info: CorePlayQueue::getTrack 13 Apr 15 08:11:00 volumio volumio[14771]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 08:11:00 volumio volumio[14771]: info: CoreCommandRouter::volumioPushState Apr 15 08:11:00 volumio volumio[14771]: info: CoreStateMachine::serviceStop Apr 15 08:11:00 volumio volumio[14771]: info: CorePlayQueue::getTrack 13 Apr 15 08:11:00 volumio volumio[14771]: info: CoreCommandRouter::serviceStop Apr 15 08:11:00 volumio volumio[14771]: info: ControllerMpd::stop Apr 15 08:11:00 volumio volumio[14771]: verbose: ControllerMpd::sendMpdCommand stop Apr 15 08:11:00 volumio volumio[14771]: info: Apr 15 08:11:00 volumio volumio[14771]: ---------------------------- MPD announces state update: player Apr 15 08:11:00 volumio volumio[14771]: info: sendMpdCommand stop took 132 milliseconds Apr 15 08:11:00 volumio volumio[14771]: info: ControllerMpd::getState Apr 15 08:11:00 volumio volumio[14771]: verbose: ControllerMpd::sendMpdCommand status Apr 15 08:11:00 volumio volumio[14771]: info: CoreStateMachine::play index undefined Apr 15 08:11:00 volumio volumio[14771]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 15 08:11:00 volumio volumio[14771]: info: CorePlayQueue::getTrack 14 Apr 15 08:11:00 volumio volumio[14771]: info: CoreStateMachine::startPlaybackTimer Apr 15 08:11:00 volumio volumio[14771]: info: CorePlayQueue::getTrack 14 Apr 15 08:11:00 volumio volumio[14771]: verbose: ControllerMpd::clearAddPlayTracks USB/A6E0-9A6E/音乐1/2caa7c95df854a09bea25b27c5c959af.mp3 Apr 15 08:11:00 volumio volumio[14771]: verbose: ControllerMpd::sendMpdCommand stop Apr 15 08:11:00 volumio volumio[14771]: info: Apr 15 08:11:00 volumio volumio[14771]: ---------------------------- MPD announces state update: player Apr 15 08:11:00 volumio volumio[14771]: info: ControllerMpd::getState Apr 15 08:11:00 volumio volumio[14771]: verbose: ControllerMpd::sendMpdCommand status Apr 15 08:11:00 volumio volumio[14771]: info: Apr 15 08:11:00 volumio volumio[14771]: ---------------------------- MPD announces state update: player Apr 15 08:11:00 volumio volumio[14771]: info: ControllerMpd::getState Apr 15 08:11:00 volumio volumio[14771]: verbose: ControllerMpd::sendMpdCommand status Apr 15 08:11:00 volumio volumio[14771]: info: sendMpdCommand status took 9 milliseconds Apr 15 08:11:00 volumio volumio[14771]: info: sendMpdCommand stop took 6 milliseconds Apr 15 08:11:00 volumio volumio[14771]: info: sendMpdCommand status took 5 milliseconds Apr 15 08:11:00 volumio volumio[14771]: verbose: ControllerMpd::parseState Apr 15 08:11:00 volumio volumio[14771]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 08:11:00 volumio volumio[14771]: verbose: ControllerMpd::sendMpdCommand clear Apr 15 08:11:00 volumio volumio[14771]: verbose: ControllerMpd::parseState Apr 15 08:11:00 volumio volumio[14771]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 08:11:00 volumio volumio[14771]: info: Apr 15 08:11:00 volumio volumio[14771]: ---------------------------- MPD announces system playlist update Apr 15 08:11:00 volumio volumio[14771]: info: Ignoring MPD Status Update Apr 15 08:11:00 volumio volumio[14771]: info: sendMpdCommand status took 8 milliseconds Apr 15 08:11:00 volumio volumio[14771]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 15 08:11:00 volumio volumio[14771]: info: sendMpdCommand clear took 5 milliseconds Apr 15 08:11:00 volumio volumio[14771]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 15 08:11:00 volumio volumio[14771]: verbose: ControllerMpd::parseState Apr 15 08:11:00 volumio volumio[14771]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 08:11:00 volumio volumio[14771]: verbose: ControllerMpd::parseTrackInfo Apr 15 08:11:00 volumio volumio[14771]: verbose: ControllerMpd::sendMpdCommand add "USB/A6E0-9A6E/音乐1/2caa7c95df854a09bea25b27c5c959af.mp3" Apr 15 08:11:00 volumio volumio[14771]: verbose: ControllerMpd::parseTrackInfo Apr 15 08:11:00 volumio volumio[14771]: info: ControllerMpd::pushState Apr 15 08:11:00 volumio volumio[14771]: info: CoreCommandRouter::servicePushState Apr 15 08:11:00 volumio volumio[14771]: info: CoreStateMachine::pushState Apr 15 08:11:00 volumio volumio[14771]: info: CorePlayQueue::getTrack 14 Apr 15 08:11:00 volumio volumio[14771]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 08:11:00 volumio volumio[14771]: info: CoreCommandRouter::volumioPushState Apr 15 08:11:00 volumio volumio[14771]: info: CorePlayQueue::getTrack 14 Apr 15 08:11:00 volumio volumio[14771]: 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":"Collapsing World","artist":"Lightscape","album":"Collapsing World","uri":"USB/A6E0-9A6E/音乐1/1d83b8ee1acc4cd0a599a76c7748abde.mp3","trackType":"mp3"} Apr 15 08:11:00 volumio volumio[14771]: verbose: CURRENT POSITION 14 Apr 15 08:11:00 volumio volumio[14771]: info: CoreStateMachine::syncState stateService stop Apr 15 08:11:00 volumio volumio[14771]: info: CoreStateMachine::syncState currentStatus stop Apr 15 08:11:00 volumio volumio[14771]: info: CoreStateMachine::pushState Apr 15 08:11:00 volumio volumio[14771]: info: CorePlayQueue::getTrack 14 Apr 15 08:11:00 volumio volumio[14771]: info: CoreCommandRouter::volumioPushState Apr 15 08:11:00 volumio volumio[14771]: info: No code Apr 15 08:11:00 volumio volumio[14771]: info: CoreStateMachine::pushState Apr 15 08:11:00 volumio volumio[14771]: info: CorePlayQueue::getTrack 14 Apr 15 08:11:00 volumio volumio[14771]: info: CoreCommandRouter::volumioPushState Apr 15 08:11:00 volumio volumio[14771]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Apr 15 08:11:00 volumio volumio[14771]: info: ------------------------------ 81ms Apr 15 08:11:00 volumio volumio[14771]: info: ------------------------------ 76ms Apr 15 08:11:00 volumio volumio[14771]: info: Apr 15 08:11:00 volumio volumio[14771]: ---------------------------- MPD announces system playlist update Apr 15 08:11:00 volumio volumio[14771]: info: Ignoring MPD Status Update Apr 15 08:11:00 volumio volumio[14771]: info: Apr 15 08:11:00 volumio volumio[14771]: ---------------------------- MPD announces system playlist update Apr 15 08:11:00 volumio volumio[14771]: info: Ignoring MPD Status Update Apr 15 08:11:00 volumio volumio[14771]: error: updateQueue error: null Apr 15 08:11:00 volumio volumio[14771]: info: Apr 15 08:11:00 volumio volumio[14771]: ---------------------------- MPD announces system playlist update Apr 15 08:11:00 volumio volumio[14771]: info: Ignoring MPD Status Update Apr 15 08:11:00 volumio volumio[14771]: info: ------------------------------ 73ms Apr 15 08:11:00 volumio volumio[14771]: info: sendMpdCommand playlistinfo took 70 milliseconds Apr 15 08:11:00 volumio volumio[14771]: info: sendMpdCommand add "USB/A6E0-9A6E/音乐1/2caa7c95df854a09bea25b27c5c959af.mp3" took 70 milliseconds Apr 15 08:11:00 volumio volumio[14771]: info: ------------------------------ 7ms Apr 15 08:11:00 volumio volumio[14771]: info: ------------------------------ 4ms Apr 15 08:11:00 volumio volumio[14771]: verbose: ControllerMpd::parseTrackInfo Apr 15 08:11:00 volumio volumio[14771]: verbose: ControllerMpd::sendMpdCommand play Apr 15 08:11:00 volumio volumio[14771]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Apr 15 08:11:00 volumio volumio[14771]: info: ------------------------------ 84ms Apr 15 08:11:00 volumio volumio[14771]: info: Apr 15 08:11:00 volumio volumio[14771]: ---------------------------- MPD announces system playlist update Apr 15 08:11:00 volumio volumio[14771]: info: Ignoring MPD Status Update Apr 15 08:11:00 volumio volumio[14771]: info: Apr 15 08:11:00 volumio volumio[14771]: ---------------------------- MPD announces system playlist update Apr 15 08:11:00 volumio volumio[14771]: info: Ignoring MPD Status Update Apr 15 08:11:00 volumio volumio[14771]: info: Apr 15 08:11:00 volumio volumio[14771]: ---------------------------- MPD announces state update: player Apr 15 08:11:00 volumio volumio[14771]: info: ------------------------------ 23ms Apr 15 08:11:00 volumio volumio[14771]: info: sendMpdCommand play took 21 milliseconds Apr 15 08:11:00 volumio volumio[14771]: info: ------------------------------ 19ms Apr 15 08:11:00 volumio volumio[14771]: info: ------------------------------ 18ms Apr 15 08:11:00 volumio volumio[14771]: info: ControllerMpd::getState Apr 15 08:11:00 volumio volumio[14771]: verbose: ControllerMpd::sendMpdCommand status Apr 15 08:11:00 volumio volumio[14771]: info: Apr 15 08:11:00 volumio volumio[14771]: ---------------------------- MPD announces state update: player Apr 15 08:11:00 volumio volumio[14771]: info: ControllerMpd::getState Apr 15 08:11:00 volumio volumio[14771]: verbose: ControllerMpd::sendMpdCommand status Apr 15 08:11:00 volumio volumio[14771]: info: Apr 15 08:11:00 volumio volumio[14771]: ---------------------------- MPD announces state update: player Apr 15 08:11:00 volumio volumio[14771]: info: ControllerMpd::getState Apr 15 08:11:00 volumio volumio[14771]: verbose: ControllerMpd::sendMpdCommand status Apr 15 08:11:00 volumio volumio[14771]: info: sendMpdCommand status took 129 milliseconds Apr 15 08:11:00 volumio volumio[14771]: verbose: ControllerMpd::parseState Apr 15 08:11:00 volumio volumio[14771]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 08:11:00 volumio volumio[14771]: info: Apr 15 08:11:00 volumio volumio[14771]: ---------------------------- MPD announces state update: player Apr 15 08:11:00 volumio volumio[14771]: info: ControllerMpd::getState Apr 15 08:11:00 volumio volumio[14771]: verbose: ControllerMpd::sendMpdCommand status Apr 15 08:11:00 volumio volumio[14771]: info: sendMpdCommand status took 129 milliseconds Apr 15 08:11:00 volumio volumio[14771]: info: sendMpdCommand status took 128 milliseconds Apr 15 08:11:00 volumio volumio[14771]: verbose: ControllerMpd::parseState Apr 15 08:11:00 volumio volumio[14771]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 08:11:00 volumio volumio[14771]: verbose: ControllerMpd::parseState Apr 15 08:11:00 volumio volumio[14771]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 08:11:00 volumio volumio[14771]: info: Apr 15 08:11:00 volumio volumio[14771]: ---------------------------- MPD announces state update: player Apr 15 08:11:00 volumio volumio[14771]: info: ControllerMpd::getState Apr 15 08:11:00 volumio volumio[14771]: verbose: ControllerMpd::sendMpdCommand status Apr 15 08:11:00 volumio volumio[14771]: info: Apr 15 08:11:00 volumio volumio[14771]: ---------------------------- MPD announces state update: player Apr 15 08:11:00 volumio volumio[14771]: info: ControllerMpd::getState Apr 15 08:11:00 volumio volumio[14771]: verbose: ControllerMpd::sendMpdCommand status Apr 15 08:11:00 volumio volumio[14771]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 15 08:11:00 volumio volumio[14771]: info: sendMpdCommand status took 4 milliseconds Apr 15 08:11:00 volumio volumio[14771]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 15 08:11:00 volumio volumio[14771]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 15 08:11:00 volumio volumio[14771]: info: sendMpdCommand status took 2 milliseconds Apr 15 08:11:00 volumio volumio[14771]: info: sendMpdCommand status took 2 milliseconds Apr 15 08:11:00 volumio volumio[14771]: verbose: ControllerMpd::parseTrackInfo Apr 15 08:11:00 volumio volumio[14771]: verbose: ControllerMpd::parseState Apr 15 08:11:00 volumio volumio[14771]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 08:11:00 volumio volumio[14771]: verbose: ControllerMpd::parseTrackInfo Apr 15 08:11:00 volumio volumio[14771]: verbose: ControllerMpd::parseTrackInfo Apr 15 08:11:00 volumio volumio[14771]: verbose: ControllerMpd::parseState Apr 15 08:11:00 volumio volumio[14771]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 08:11:00 volumio volumio[14771]: verbose: ControllerMpd::parseState Apr 15 08:11:00 volumio volumio[14771]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 08:11:00 volumio volumio[14771]: info: ControllerMpd::pushState Apr 15 08:11:00 volumio volumio[14771]: info: CoreCommandRouter::servicePushState Apr 15 08:11:00 volumio volumio[14771]: info: CorePlayQueue::getTrack 14 Apr 15 08:11:00 volumio volumio[14771]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":163,"samplerate":"22.05 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"Daylight","artist":"Seredris","album":"Daylight","uri":"USB/A6E0-9A6E/音乐1/2caa7c95df854a09bea25b27c5c959af.mp3","trackType":"mp3"} Apr 15 08:11:00 volumio volumio[14771]: verbose: CURRENT POSITION 14 Apr 15 08:11:00 volumio volumio[14771]: info: CoreStateMachine::syncState stateService play Apr 15 08:11:00 volumio volumio[14771]: info: CoreStateMachine::syncState currentStatus stop Apr 15 08:11:00 volumio volumio[14771]: info: ControllerMpd::pushState Apr 15 08:11:00 volumio volumio[14771]: info: CoreCommandRouter::servicePushState Apr 15 08:11:00 volumio volumio[14771]: info: CorePlayQueue::getTrack 14 Apr 15 08:11:00 volumio volumio[14771]: verbose: STATE SERVICE {"status":"play","position":0,"seek":912,"duration":163,"samplerate":"22.05 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"Daylight","artist":"Seredris","album":"Daylight","uri":"USB/A6E0-9A6E/音乐1/2caa7c95df854a09bea25b27c5c959af.mp3","trackType":"mp3"} Apr 15 08:11:00 volumio volumio[14771]: verbose: CURRENT POSITION 14 Apr 15 08:11:00 volumio volumio[14771]: info: CoreStateMachine::syncState stateService play Apr 15 08:11:00 volumio volumio[14771]: info: CoreStateMachine::syncState currentStatus play Apr 15 08:11:00 volumio volumio[14771]: info: Received an update from plugin. extracting info from payload Apr 15 08:11:00 volumio volumio[14771]: info: CoreStateMachine::pushState Apr 15 08:11:00 volumio volumio[14771]: info: CorePlayQueue::getTrack 14 Apr 15 08:11:00 volumio volumio[14771]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 08:11:00 volumio volumio[14771]: info: CoreCommandRouter::volumioPushState Apr 15 08:11:00 volumio volumio[14771]: info: CoreStateMachine::pushState Apr 15 08:11:00 volumio volumio[14771]: info: CorePlayQueue::getTrack 14 Apr 15 08:11:00 volumio volumio[14771]: info: CoreCommandRouter::volumioPushState Apr 15 08:11:00 volumio volumio[14771]: info: ControllerMpd::pushState Apr 15 08:11:00 volumio volumio[14771]: info: CoreCommandRouter::servicePushState Apr 15 08:11:00 volumio volumio[14771]: info: CorePlayQueue::getTrack 14 Apr 15 08:11:00 volumio volumio[14771]: verbose: STATE SERVICE {"status":"play","position":0,"seek":912,"duration":163,"samplerate":"22.05 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"Daylight","artist":"Seredris","album":"Daylight","uri":"USB/A6E0-9A6E/音乐1/2caa7c95df854a09bea25b27c5c959af.mp3","trackType":"mp3"} Apr 15 08:11:00 volumio volumio[14771]: verbose: CURRENT POSITION 14 Apr 15 08:11:00 volumio volumio[14771]: info: CoreStateMachine::syncState stateService play Apr 15 08:11:00 volumio volumio[14771]: info: CoreStateMachine::syncState currentStatus play Apr 15 08:11:00 volumio volumio[14771]: info: Received an update from plugin. extracting info from payload Apr 15 08:11:00 volumio volumio[14771]: info: CoreStateMachine::pushState Apr 15 08:11:00 volumio volumio[14771]: info: CorePlayQueue::getTrack 14 Apr 15 08:11:00 volumio volumio[14771]: info: CoreCommandRouter::volumioPushState Apr 15 08:11:00 volumio volumio[14771]: info: CoreStateMachine::pushState Apr 15 08:11:00 volumio volumio[14771]: info: CorePlayQueue::getTrack 14 Apr 15 08:11:00 volumio volumio[14771]: info: CoreCommandRouter::volumioPushState Apr 15 08:11:00 volumio volumio[14771]: info: ------------------------------ 152ms Apr 15 08:11:00 volumio volumio[14771]: info: ------------------------------ 181ms Apr 15 08:11:00 volumio volumio[14771]: info: ------------------------------ 180ms Apr 15 08:11:00 volumio volumio[14771]: info: sendMpdCommand playlistinfo took 48 milliseconds Apr 15 08:11:00 volumio volumio[14771]: info: sendMpdCommand playlistinfo took 48 milliseconds Apr 15 08:11:00 volumio volumio[14771]: info: sendMpdCommand playlistinfo took 48 milliseconds Apr 15 08:11:00 volumio volumio[14771]: verbose: ControllerMpd::parseTrackInfo Apr 15 08:11:00 volumio volumio[14771]: verbose: ControllerMpd::parseTrackInfo Apr 15 08:11:00 volumio volumio[14771]: verbose: ControllerMpd::parseTrackInfo Apr 15 08:11:00 volumio volumio[14771]: info: ControllerMpd::pushState Apr 15 08:11:00 volumio volumio[14771]: info: CoreCommandRouter::servicePushState Apr 15 08:11:00 volumio volumio[14771]: info: CorePlayQueue::getTrack 14 Apr 15 08:11:00 volumio volumio[14771]: verbose: STATE SERVICE {"status":"play","position":0,"seek":912,"duration":163,"samplerate":"22.05 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"Daylight","artist":"Seredris","album":"Daylight","uri":"USB/A6E0-9A6E/音乐1/2caa7c95df854a09bea25b27c5c959af.mp3","trackType":"mp3"} Apr 15 08:11:00 volumio volumio[14771]: verbose: CURRENT POSITION 14 Apr 15 08:11:00 volumio volumio[14771]: info: CoreStateMachine::syncState stateService play Apr 15 08:11:00 volumio volumio[14771]: info: CoreStateMachine::syncState currentStatus play Apr 15 08:11:00 volumio volumio[14771]: info: Received an update from plugin. extracting info from payload Apr 15 08:11:00 volumio volumio[14771]: info: CoreStateMachine::pushState Apr 15 08:11:00 volumio volumio[14771]: info: CorePlayQueue::getTrack 14 Apr 15 08:11:00 volumio volumio[14771]: info: CoreCommandRouter::volumioPushState Apr 15 08:11:00 volumio volumio[14771]: info: CoreStateMachine::pushState Apr 15 08:11:00 volumio volumio[14771]: info: CorePlayQueue::getTrack 14 Apr 15 08:11:00 volumio volumio[14771]: info: CoreCommandRouter::volumioPushState Apr 15 08:11:00 volumio volumio[14771]: info: ControllerMpd::pushState Apr 15 08:11:00 volumio volumio[14771]: info: CoreCommandRouter::servicePushState Apr 15 08:11:00 volumio volumio[14771]: info: CorePlayQueue::getTrack 14 Apr 15 08:11:00 volumio volumio[14771]: verbose: STATE SERVICE {"status":"play","position":0,"seek":912,"duration":163,"samplerate":"22.05 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"Daylight","artist":"Seredris","album":"Daylight","uri":"USB/A6E0-9A6E/音乐1/2caa7c95df854a09bea25b27c5c959af.mp3","trackType":"mp3"} Apr 15 08:11:00 volumio volumio[14771]: verbose: CURRENT POSITION 14 Apr 15 08:11:00 volumio volumio[14771]: info: CoreStateMachine::syncState stateService play Apr 15 08:11:00 volumio volumio[14771]: info: CoreStateMachine::syncState currentStatus play Apr 15 08:11:00 volumio volumio[14771]: info: Received an update from plugin. extracting info from payload Apr 15 08:11:00 volumio volumio[14771]: info: CoreStateMachine::pushState Apr 15 08:11:00 volumio volumio[14771]: info: CorePlayQueue::getTrack 14 Apr 15 08:11:00 volumio volumio[14771]: info: CoreCommandRouter::volumioPushState Apr 15 08:11:00 volumio volumio[14771]: info: CoreStateMachine::pushState Apr 15 08:11:00 volumio volumio[14771]: info: CorePlayQueue::getTrack 14 Apr 15 08:11:00 volumio volumio[14771]: info: CoreCommandRouter::volumioPushState Apr 15 08:11:00 volumio volumio[14771]: info: ControllerMpd::pushState Apr 15 08:11:00 volumio volumio[14771]: info: CoreCommandRouter::servicePushState Apr 15 08:11:00 volumio volumio[14771]: info: CorePlayQueue::getTrack 14 Apr 15 08:11:00 volumio volumio[14771]: verbose: STATE SERVICE {"status":"play","position":0,"seek":912,"duration":163,"samplerate":"22.05 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"Daylight","artist":"Seredris","album":"Daylight","uri":"USB/A6E0-9A6E/音乐1/2caa7c95df854a09bea25b27c5c959af.mp3","trackType":"mp3"} Apr 15 08:11:00 volumio volumio[14771]: verbose: CURRENT POSITION 14 Apr 15 08:11:00 volumio volumio[14771]: info: CoreStateMachine::syncState stateService play Apr 15 08:11:00 volumio volumio[14771]: info: CoreStateMachine::syncState currentStatus play Apr 15 08:11:00 volumio volumio[14771]: info: Received an update from plugin. extracting info from payload Apr 15 08:11:00 volumio volumio[14771]: info: CoreStateMachine::pushState Apr 15 08:11:00 volumio volumio[14771]: info: CorePlayQueue::getTrack 14 Apr 15 08:11:00 volumio volumio[14771]: info: CoreCommandRouter::volumioPushState Apr 15 08:11:00 volumio volumio[14771]: info: CoreStateMachine::pushState Apr 15 08:11:00 volumio volumio[14771]: info: CorePlayQueue::getTrack 14 Apr 15 08:11:00 volumio volumio[14771]: info: CoreCommandRouter::volumioPushState Apr 15 08:11:00 volumio volumio[14771]: info: ------------------------------ 118ms Apr 15 08:11:00 volumio volumio[14771]: info: ------------------------------ 117ms Apr 15 08:11:00 volumio volumio[14771]: info: ------------------------------ 116ms Apr 15 08:11:02 volumio volumio[14771]: info: CoreCommandRouter::volumioPlay Apr 15 08:11:02 volumio volumio[14771]: info: CoreStateMachine::play index 15 Apr 15 08:11:02 volumio volumio[14771]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 15 08:11:02 volumio volumio[14771]: info: CoreStateMachine::stop Apr 15 08:11:02 volumio volumio[14771]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 15 08:11:02 volumio volumio[14771]: info: CoreStateMachine::stPlaybackTimer Apr 15 08:11:02 volumio volumio[14771]: info: CoreStateMachine::updateTrackBlock Apr 15 08:11:02 volumio volumio[14771]: info: CorePlayQueue::getTrackBlock Apr 15 08:11:02 volumio volumio[14771]: info: CoreStateMachine::pushState Apr 15 08:11:02 volumio volumio[14771]: info: CorePlayQueue::getTrack 14 Apr 15 08:11:02 volumio volumio[14771]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 08:11:02 volumio volumio[14771]: info: CoreCommandRouter::volumioPushState Apr 15 08:11:02 volumio volumio[14771]: info: CoreStateMachine::serviceStop Apr 15 08:11:02 volumio volumio[14771]: info: CorePlayQueue::getTrack 14 Apr 15 08:11:02 volumio volumio[14771]: info: CoreCommandRouter::serviceStop Apr 15 08:11:02 volumio volumio[14771]: info: ControllerMpd::stop Apr 15 08:11:02 volumio volumio[14771]: verbose: ControllerMpd::sendMpdCommand stop Apr 15 08:11:02 volumio volumio[14771]: info: Apr 15 08:11:02 volumio volumio[14771]: ---------------------------- MPD announces state update: player Apr 15 08:11:02 volumio volumio[14771]: info: sendMpdCommand stop took 135 milliseconds Apr 15 08:11:02 volumio volumio[14771]: info: ControllerMpd::getState Apr 15 08:11:02 volumio volumio[14771]: verbose: ControllerMpd::sendMpdCommand status Apr 15 08:11:02 volumio volumio[14771]: info: CoreStateMachine::play index undefined Apr 15 08:11:02 volumio volumio[14771]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 15 08:11:02 volumio volumio[14771]: info: CorePlayQueue::getTrack 15 Apr 15 08:11:02 volumio volumio[14771]: info: CoreStateMachine::startPlaybackTimer Apr 15 08:11:02 volumio volumio[14771]: info: CorePlayQueue::getTrack 15 Apr 15 08:11:02 volumio volumio[14771]: verbose: ControllerMpd::clearAddPlayTracks USB/A6E0-9A6E/音乐1/a297251f9c4044e28eca73180dde0e95.mp3 Apr 15 08:11:02 volumio volumio[14771]: verbose: ControllerMpd::sendMpdCommand stop Apr 15 08:11:02 volumio volumio[14771]: info: Apr 15 08:11:02 volumio volumio[14771]: ---------------------------- MPD announces state update: player Apr 15 08:11:02 volumio volumio[14771]: info: ControllerMpd::getState Apr 15 08:11:02 volumio volumio[14771]: verbose: ControllerMpd::sendMpdCommand status Apr 15 08:11:02 volumio volumio[14771]: info: Apr 15 08:11:02 volumio volumio[14771]: ---------------------------- MPD announces state update: player Apr 15 08:11:02 volumio volumio[14771]: info: ControllerMpd::getState Apr 15 08:11:02 volumio volumio[14771]: verbose: ControllerMpd::sendMpdCommand status Apr 15 08:11:02 volumio volumio[14771]: info: sendMpdCommand status took 5 milliseconds Apr 15 08:11:02 volumio volumio[14771]: info: sendMpdCommand stop took 4 milliseconds Apr 15 08:11:02 volumio volumio[14771]: info: sendMpdCommand status took 3 milliseconds Apr 15 08:11:02 volumio volumio[14771]: verbose: ControllerMpd::parseState Apr 15 08:11:02 volumio volumio[14771]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 08:11:02 volumio volumio[14771]: verbose: ControllerMpd::sendMpdCommand clear Apr 15 08:11:02 volumio volumio[14771]: verbose: ControllerMpd::parseState Apr 15 08:11:02 volumio volumio[14771]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 08:11:02 volumio volumio[14771]: info: Apr 15 08:11:02 volumio volumio[14771]: ---------------------------- MPD announces system playlist update Apr 15 08:11:02 volumio volumio[14771]: info: Ignoring MPD Status Update Apr 15 08:11:02 volumio volumio[14771]: info: sendMpdCommand status took 4 milliseconds Apr 15 08:11:02 volumio volumio[14771]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 15 08:11:02 volumio volumio[14771]: info: sendMpdCommand clear took 3 milliseconds Apr 15 08:11:02 volumio volumio[14771]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 15 08:11:02 volumio volumio[14771]: verbose: ControllerMpd::parseState Apr 15 08:11:02 volumio volumio[14771]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 08:11:02 volumio volumio[14771]: verbose: ControllerMpd::parseTrackInfo Apr 15 08:11:02 volumio volumio[14771]: verbose: ControllerMpd::sendMpdCommand add "USB/A6E0-9A6E/音乐1/a297251f9c4044e28eca73180dde0e95.mp3" Apr 15 08:11:02 volumio volumio[14771]: verbose: ControllerMpd::parseTrackInfo Apr 15 08:11:02 volumio volumio[14771]: info: ControllerMpd::pushState Apr 15 08:11:02 volumio volumio[14771]: info: CoreCommandRouter::servicePushState Apr 15 08:11:02 volumio volumio[14771]: info: CoreStateMachine::pushState Apr 15 08:11:02 volumio volumio[14771]: info: CorePlayQueue::getTrack 15 Apr 15 08:11:02 volumio volumio[14771]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 08:11:02 volumio volumio[14771]: info: CoreCommandRouter::volumioPushState Apr 15 08:11:02 volumio volumio[14771]: info: CorePlayQueue::getTrack 15 Apr 15 08:11:02 volumio volumio[14771]: 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":"Daylight","artist":"Seredris","album":"Daylight","uri":"USB/A6E0-9A6E/音乐1/2caa7c95df854a09bea25b27c5c959af.mp3","trackType":"mp3"} Apr 15 08:11:02 volumio volumio[14771]: verbose: CURRENT POSITION 15 Apr 15 08:11:02 volumio volumio[14771]: info: CoreStateMachine::syncState stateService stop Apr 15 08:11:02 volumio volumio[14771]: info: CoreStateMachine::syncState currentStatus stop Apr 15 08:11:02 volumio volumio[14771]: info: CoreStateMachine::pushState Apr 15 08:11:02 volumio volumio[14771]: info: CorePlayQueue::getTrack 15 Apr 15 08:11:02 volumio volumio[14771]: info: CoreCommandRouter::volumioPushState Apr 15 08:11:02 volumio volumio[14771]: info: No code Apr 15 08:11:02 volumio volumio[14771]: info: CoreStateMachine::pushState Apr 15 08:11:02 volumio volumio[14771]: info: CorePlayQueue::getTrack 15 Apr 15 08:11:02 volumio volumio[14771]: info: CoreCommandRouter::volumioPushState Apr 15 08:11:02 volumio volumio[14771]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Apr 15 08:11:02 volumio volumio[14771]: info: ------------------------------ 48ms Apr 15 08:11:02 volumio volumio[14771]: info: ------------------------------ 45ms Apr 15 08:11:02 volumio volumio[14771]: info: Apr 15 08:11:02 volumio volumio[14771]: ---------------------------- MPD announces system playlist update Apr 15 08:11:02 volumio volumio[14771]: info: Ignoring MPD Status Update Apr 15 08:11:02 volumio volumio[14771]: info: Apr 15 08:11:02 volumio volumio[14771]: ---------------------------- MPD announces system playlist update Apr 15 08:11:02 volumio volumio[14771]: info: Ignoring MPD Status Update Apr 15 08:11:02 volumio volumio[14771]: error: updateQueue error: null Apr 15 08:11:02 volumio volumio[14771]: info: Apr 15 08:11:02 volumio volumio[14771]: ---------------------------- MPD announces system playlist update Apr 15 08:11:02 volumio volumio[14771]: info: Ignoring MPD Status Update Apr 15 08:11:02 volumio volumio[14771]: info: ------------------------------ 44ms Apr 15 08:11:02 volumio volumio[14771]: info: sendMpdCommand playlistinfo took 43 milliseconds Apr 15 08:11:02 volumio volumio[14771]: info: sendMpdCommand add "USB/A6E0-9A6E/音乐1/a297251f9c4044e28eca73180dde0e95.mp3" took 42 milliseconds Apr 15 08:11:02 volumio volumio[14771]: info: ------------------------------ 3ms Apr 15 08:11:02 volumio volumio[14771]: info: ------------------------------ 3ms Apr 15 08:11:02 volumio volumio[14771]: verbose: ControllerMpd::parseTrackInfo Apr 15 08:11:02 volumio volumio[14771]: verbose: ControllerMpd::sendMpdCommand play Apr 15 08:11:02 volumio volumio[14771]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Apr 15 08:11:02 volumio volumio[14771]: info: ------------------------------ 51ms Apr 15 08:11:02 volumio volumio[14771]: info: Apr 15 08:11:02 volumio volumio[14771]: ---------------------------- MPD announces system playlist update Apr 15 08:11:02 volumio volumio[14771]: info: Ignoring MPD Status Update Apr 15 08:11:02 volumio volumio[14771]: info: Apr 15 08:11:02 volumio volumio[14771]: ---------------------------- MPD announces system playlist update Apr 15 08:11:02 volumio volumio[14771]: info: Ignoring MPD Status Update Apr 15 08:11:02 volumio volumio[14771]: info: Apr 15 08:11:02 volumio volumio[14771]: ---------------------------- MPD announces state update: player Apr 15 08:11:02 volumio volumio[14771]: info: ------------------------------ 20ms Apr 15 08:11:02 volumio volumio[14771]: info: sendMpdCommand play took 17 milliseconds Apr 15 08:11:02 volumio volumio[14771]: info: ------------------------------ 16ms Apr 15 08:11:02 volumio volumio[14771]: info: ------------------------------ 15ms Apr 15 08:11:02 volumio volumio[14771]: info: ControllerMpd::getState Apr 15 08:11:02 volumio volumio[14771]: verbose: ControllerMpd::sendMpdCommand status Apr 15 08:11:02 volumio volumio[14771]: info: Apr 15 08:11:02 volumio volumio[14771]: ---------------------------- MPD announces state update: player Apr 15 08:11:02 volumio volumio[14771]: info: ControllerMpd::getState Apr 15 08:11:02 volumio volumio[14771]: verbose: ControllerMpd::sendMpdCommand status Apr 15 08:11:02 volumio volumio[14771]: info: Apr 15 08:11:02 volumio volumio[14771]: ---------------------------- MPD announces state update: player Apr 15 08:11:02 volumio volumio[14771]: info: ControllerMpd::getState Apr 15 08:11:02 volumio volumio[14771]: verbose: ControllerMpd::sendMpdCommand status Apr 15 08:11:02 volumio volumio[14771]: info: Apr 15 08:11:02 volumio volumio[14771]: ---------------------------- MPD announces state update: player Apr 15 08:11:02 volumio volumio[14771]: info: sendMpdCommand status took 118 milliseconds Apr 15 08:11:02 volumio volumio[14771]: info: sendMpdCommand status took 118 milliseconds Apr 15 08:11:02 volumio volumio[14771]: info: sendMpdCommand status took 117 milliseconds Apr 15 08:11:02 volumio volumio[14771]: info: ControllerMpd::getState Apr 15 08:11:02 volumio volumio[14771]: verbose: ControllerMpd::sendMpdCommand status Apr 15 08:11:02 volumio volumio[14771]: verbose: ControllerMpd::parseState Apr 15 08:11:02 volumio volumio[14771]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 08:11:02 volumio volumio[14771]: verbose: ControllerMpd::parseState Apr 15 08:11:02 volumio volumio[14771]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 08:11:02 volumio volumio[14771]: verbose: ControllerMpd::parseState Apr 15 08:11:02 volumio volumio[14771]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 08:11:02 volumio volumio[14771]: info: Apr 15 08:11:02 volumio volumio[14771]: ---------------------------- MPD announces state update: player Apr 15 08:11:02 volumio volumio[14771]: info: ControllerMpd::getState Apr 15 08:11:02 volumio volumio[14771]: verbose: ControllerMpd::sendMpdCommand status Apr 15 08:11:02 volumio volumio[14771]: info: Apr 15 08:11:02 volumio volumio[14771]: ---------------------------- MPD announces state update: player Apr 15 08:11:02 volumio volumio[14771]: info: ControllerMpd::getState Apr 15 08:11:02 volumio volumio[14771]: verbose: ControllerMpd::sendMpdCommand status Apr 15 08:11:02 volumio volumio[14771]: info: sendMpdCommand status took 5 milliseconds Apr 15 08:11:02 volumio volumio[14771]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 15 08:11:02 volumio volumio[14771]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 15 08:11:02 volumio volumio[14771]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 15 08:11:02 volumio volumio[14771]: info: sendMpdCommand status took 4 milliseconds Apr 15 08:11:02 volumio volumio[14771]: info: sendMpdCommand status took 3 milliseconds Apr 15 08:11:02 volumio volumio[14771]: verbose: ControllerMpd::parseState Apr 15 08:11:02 volumio volumio[14771]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 08:11:02 volumio volumio[14771]: verbose: ControllerMpd::parseTrackInfo Apr 15 08:11:02 volumio volumio[14771]: verbose: ControllerMpd::parseTrackInfo Apr 15 08:11:02 volumio volumio[14771]: verbose: ControllerMpd::parseTrackInfo Apr 15 08:11:02 volumio volumio[14771]: verbose: ControllerMpd::parseState Apr 15 08:11:02 volumio volumio[14771]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 08:11:02 volumio volumio[14771]: verbose: ControllerMpd::parseState Apr 15 08:11:02 volumio volumio[14771]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 08:11:02 volumio volumio[14771]: info: ControllerMpd::pushState Apr 15 08:11:02 volumio volumio[14771]: info: CoreCommandRouter::servicePushState Apr 15 08:11:02 volumio volumio[14771]: info: CorePlayQueue::getTrack 15 Apr 15 08:11:02 volumio volumio[14771]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":170,"samplerate":"22.05 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"Empty Love","artist":"Lulleaux/Kid Princess","album":"Empty Love (feat. Kid Princess)","uri":"USB/A6E0-9A6E/音乐1/a297251f9c4044e28eca73180dde0e95.mp3","trackType":"mp3"} Apr 15 08:11:02 volumio volumio[14771]: verbose: CURRENT POSITION 15 Apr 15 08:11:02 volumio volumio[14771]: info: CoreStateMachine::syncState stateService play Apr 15 08:11:02 volumio volumio[14771]: info: CoreStateMachine::syncState currentStatus stop Apr 15 08:11:02 volumio volumio[14771]: info: ControllerMpd::pushState Apr 15 08:11:02 volumio volumio[14771]: info: CoreCommandRouter::servicePushState Apr 15 08:11:02 volumio volumio[14771]: info: CorePlayQueue::getTrack 15 Apr 15 08:11:02 volumio volumio[14771]: verbose: STATE SERVICE {"status":"play","position":0,"seek":410,"duration":170,"samplerate":"22.05 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"Empty Love","artist":"Lulleaux/Kid Princess","album":"Empty Love (feat. Kid Princess)","uri":"USB/A6E0-9A6E/音乐1/a297251f9c4044e28eca73180dde0e95.mp3","trackType":"mp3"} Apr 15 08:11:02 volumio volumio[14771]: verbose: CURRENT POSITION 15 Apr 15 08:11:02 volumio volumio[14771]: info: CoreStateMachine::syncState stateService play Apr 15 08:11:02 volumio volumio[14771]: info: CoreStateMachine::syncState currentStatus play Apr 15 08:11:02 volumio volumio[14771]: info: Received an update from plugin. extracting info from payload Apr 15 08:11:02 volumio volumio[14771]: info: CoreStateMachine::pushState Apr 15 08:11:02 volumio volumio[14771]: info: CorePlayQueue::getTrack 15 Apr 15 08:11:02 volumio volumio[14771]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 08:11:02 volumio volumio[14771]: info: CoreCommandRouter::volumioPushState Apr 15 08:11:02 volumio volumio[14771]: info: CoreStateMachine::pushState Apr 15 08:11:02 volumio volumio[14771]: info: CorePlayQueue::getTrack 15 Apr 15 08:11:02 volumio volumio[14771]: info: CoreCommandRouter::volumioPushState Apr 15 08:11:02 volumio volumio[14771]: info: ControllerMpd::pushState Apr 15 08:11:02 volumio volumio[14771]: info: CoreCommandRouter::servicePushState Apr 15 08:11:02 volumio volumio[14771]: info: CorePlayQueue::getTrack 15 Apr 15 08:11:02 volumio volumio[14771]: verbose: STATE SERVICE {"status":"play","position":0,"seek":547,"duration":170,"samplerate":"22.05 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"Empty Love","artist":"Lulleaux/Kid Princess","album":"Empty Love (feat. Kid Princess)","uri":"USB/A6E0-9A6E/音乐1/a297251f9c4044e28eca73180dde0e95.mp3","trackType":"mp3"} Apr 15 08:11:02 volumio volumio[14771]: verbose: CURRENT POSITION 15 Apr 15 08:11:02 volumio volumio[14771]: info: CoreStateMachine::syncState stateService play Apr 15 08:11:02 volumio volumio[14771]: info: CoreStateMachine::syncState currentStatus play Apr 15 08:11:02 volumio volumio[14771]: info: Received an update from plugin. extracting info from payload Apr 15 08:11:02 volumio volumio[14771]: info: CoreStateMachine::pushState Apr 15 08:11:02 volumio volumio[14771]: info: CorePlayQueue::getTrack 15 Apr 15 08:11:02 volumio volumio[14771]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 08:11:02 volumio volumio[14771]: info: CoreCommandRouter::volumioPushState Apr 15 08:11:02 volumio volumio[14771]: info: CoreStateMachine::pushState Apr 15 08:11:02 volumio volumio[14771]: info: CorePlayQueue::getTrack 15 Apr 15 08:11:02 volumio volumio[14771]: info: CoreCommandRouter::volumioPushState Apr 15 08:11:02 volumio volumio[14771]: info: ------------------------------ 144ms Apr 15 08:11:02 volumio volumio[14771]: info: ------------------------------ 181ms Apr 15 08:11:02 volumio volumio[14771]: info: ------------------------------ 180ms Apr 15 08:11:02 volumio volumio[14771]: info: sendMpdCommand playlistinfo took 59 milliseconds Apr 15 08:11:02 volumio volumio[14771]: info: sendMpdCommand playlistinfo took 58 milliseconds Apr 15 08:11:02 volumio volumio[14771]: info: sendMpdCommand playlistinfo took 61 milliseconds Apr 15 08:11:02 volumio volumio[14771]: verbose: ControllerMpd::parseTrackInfo Apr 15 08:11:02 volumio volumio[14771]: verbose: ControllerMpd::parseTrackInfo Apr 15 08:11:02 volumio volumio[14771]: verbose: ControllerMpd::parseTrackInfo Apr 15 08:11:02 volumio volumio[14771]: info: ControllerMpd::pushState Apr 15 08:11:02 volumio volumio[14771]: info: CoreCommandRouter::servicePushState Apr 15 08:11:02 volumio volumio[14771]: info: CorePlayQueue::getTrack 15 Apr 15 08:11:02 volumio volumio[14771]: verbose: STATE SERVICE {"status":"play","position":0,"seek":684,"duration":170,"samplerate":"22.05 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"Empty Love","artist":"Lulleaux/Kid Princess","album":"Empty Love (feat. Kid Princess)","uri":"USB/A6E0-9A6E/音乐1/a297251f9c4044e28eca73180dde0e95.mp3","trackType":"mp3"} Apr 15 08:11:02 volumio volumio[14771]: verbose: CURRENT POSITION 15 Apr 15 08:11:02 volumio volumio[14771]: info: CoreStateMachine::syncState stateService play Apr 15 08:11:02 volumio volumio[14771]: info: CoreStateMachine::syncState currentStatus play Apr 15 08:11:02 volumio volumio[14771]: info: Received an update from plugin. extracting info from payload Apr 15 08:11:02 volumio volumio[14771]: info: CoreStateMachine::pushState Apr 15 08:11:02 volumio volumio[14771]: info: CorePlayQueue::getTrack 15 Apr 15 08:11:02 volumio volumio[14771]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 08:11:02 volumio volumio[14771]: info: CoreCommandRouter::volumioPushState Apr 15 08:11:02 volumio volumio[14771]: info: CoreStateMachine::pushState Apr 15 08:11:02 volumio volumio[14771]: info: CorePlayQueue::getTrack 15 Apr 15 08:11:02 volumio volumio[14771]: info: CoreCommandRouter::volumioPushState Apr 15 08:11:02 volumio volumio[14771]: info: ControllerMpd::pushState Apr 15 08:11:02 volumio volumio[14771]: info: CoreCommandRouter::servicePushState Apr 15 08:11:02 volumio volumio[14771]: info: CorePlayQueue::getTrack 15 Apr 15 08:11:02 volumio volumio[14771]: verbose: STATE SERVICE {"status":"play","position":0,"seek":821,"duration":170,"samplerate":"22.05 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"Empty Love","artist":"Lulleaux/Kid Princess","album":"Empty Love (feat. Kid Princess)","uri":"USB/A6E0-9A6E/音乐1/a297251f9c4044e28eca73180dde0e95.mp3","trackType":"mp3"} Apr 15 08:11:02 volumio volumio[14771]: verbose: CURRENT POSITION 15 Apr 15 08:11:02 volumio volumio[14771]: info: CoreStateMachine::syncState stateService play Apr 15 08:11:02 volumio volumio[14771]: info: CoreStateMachine::syncState currentStatus play Apr 15 08:11:02 volumio volumio[14771]: info: Received an update from plugin. extracting info from payload Apr 15 08:11:02 volumio volumio[14771]: info: CoreStateMachine::pushState Apr 15 08:11:02 volumio volumio[14771]: info: CorePlayQueue::getTrack 15 Apr 15 08:11:02 volumio volumio[14771]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 08:11:02 volumio volumio[14771]: info: CoreCommandRouter::volumioPushState Apr 15 08:11:02 volumio volumio[14771]: info: CoreStateMachine::pushState Apr 15 08:11:02 volumio volumio[14771]: info: CorePlayQueue::getTrack 15 Apr 15 08:11:02 volumio volumio[14771]: info: CoreCommandRouter::volumioPushState Apr 15 08:11:02 volumio volumio[14771]: info: ControllerMpd::pushState Apr 15 08:11:02 volumio volumio[14771]: info: CoreCommandRouter::servicePushState Apr 15 08:11:02 volumio volumio[14771]: info: CorePlayQueue::getTrack 15 Apr 15 08:11:02 volumio volumio[14771]: verbose: STATE SERVICE {"status":"play","position":0,"seek":821,"duration":170,"samplerate":"22.05 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"Empty Love","artist":"Lulleaux/Kid Princess","album":"Empty Love (feat. Kid Princess)","uri":"USB/A6E0-9A6E/音乐1/a297251f9c4044e28eca73180dde0e95.mp3","trackType":"mp3"} Apr 15 08:11:02 volumio volumio[14771]: verbose: CURRENT POSITION 15 Apr 15 08:11:02 volumio volumio[14771]: info: CoreStateMachine::syncState stateService play Apr 15 08:11:02 volumio volumio[14771]: info: CoreStateMachine::syncState currentStatus play Apr 15 08:11:02 volumio volumio[14771]: info: Received an update from plugin. extracting info from payload Apr 15 08:11:02 volumio volumio[14771]: info: CoreStateMachine::pushState Apr 15 08:11:02 volumio volumio[14771]: info: CorePlayQueue::getTrack 15 Apr 15 08:11:02 volumio volumio[14771]: info: CoreCommandRouter::volumioPushState Apr 15 08:11:02 volumio volumio[14771]: info: CoreStateMachine::pushState Apr 15 08:11:02 volumio volumio[14771]: info: CorePlayQueue::getTrack 15 Apr 15 08:11:02 volumio volumio[14771]: info: CoreCommandRouter::volumioPushState Apr 15 08:11:03 volumio volumio[14771]: info: ------------------------------ 135ms Apr 15 08:11:03 volumio volumio[14771]: info: ------------------------------ 133ms Apr 15 08:11:03 volumio volumio[14771]: info: ------------------------------ 132ms Apr 15 08:11:07 volumio volumio[14771]: info: VolumeController::SetAlsaVolume35 Apr 15 08:11:07 volumio volumio[14771]: info: CoreStateMachine::pushState Apr 15 08:11:07 volumio volumio[14771]: info: CorePlayQueue::getTrack 15 Apr 15 08:11:07 volumio volumio[14771]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 08:11:07 volumio volumio[14771]: info: CoreCommandRouter::volumioPushState Apr 15 08:12:44 volumio volumio[14771]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 15 08:12:44 volumio volumio[14771]: Error: connect ETIMEDOUT 128.242.240.221:443 Apr 15 08:12:44 volumio volumio[14771]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Apr 15 08:12:44 volumio volumio[14771]: errno: -110, Apr 15 08:12:44 volumio volumio[14771]: code: 'ETIMEDOUT', Apr 15 08:12:44 volumio volumio[14771]: syscall: 'connect', Apr 15 08:12:44 volumio volumio[14771]: address: '128.242.240.221', Apr 15 08:12:44 volumio volumio[14771]: port: 443 Apr 15 08:12:44 volumio volumio[14771]: } Apr 15 08:12:44 volumio volumio[14771]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 15 08:12:44 volumio sudo[19718]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-04-15 08:11' Apr 15 08:12:44 volumio sudo[19718]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:31:57 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6759e875c98b942866984e844891d6c1"