-- Logs begin at Thu 2019-02-14 18:11:59 CST, end at Wed 2025-07-02 20:50:30 CST. -- Jul 02 20:49:09 nnn volumio[951]: info: Clearing queue after UPNP request Jul 02 20:49:09 nnn volumio[951]: verbose: UNSET VOLATILE: Service: mpd Jul 02 20:49:09 nnn volumio[951]: info: Stopping Airplay Playback and sending pause command to client via USR2 Jul 02 20:49:10 nnn sudo[2398]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 Jul 02 20:49:10 nnn sudo[2398]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 02 20:49:10 nnn sudo[2398]: pam_unix(sudo:session): session closed for user root Jul 02 20:49:10 nnn volumio[951]: info: Cannot execute Shairport-sync USR2 kill: Error: Command failed: /usr/bin/sudo /bin/kill -USR2 $(pidof shairport-sync) Jul 02 20:49:10 nnn volumio[951]: Usage: Jul 02 20:49:10 nnn volumio[951]: kill [options] [...] Jul 02 20:49:10 nnn volumio[951]: Options: Jul 02 20:49:10 nnn volumio[951]: [...] send signal to every listed Jul 02 20:49:10 nnn volumio[951]: -, -s, --signal Jul 02 20:49:10 nnn volumio[951]: specify the to be sent Jul 02 20:49:10 nnn volumio[951]: -l, --list=[] list all signal names, or convert one to a name Jul 02 20:49:10 nnn volumio[951]: -L, --table list all signal names in a nice table Jul 02 20:49:10 nnn volumio[951]: -h, --help display this help and exit Jul 02 20:49:10 nnn volumio[951]: -V, --version output version information and exit Jul 02 20:49:10 nnn volumio[951]: For more details see kill(1). Jul 02 20:49:10 nnn volumio[951]: info: CoreStateMachine::ClearQueue Jul 02 20:49:10 nnn volumio[951]: info: CoreStateMachine::stop Jul 02 20:49:10 nnn volumio[951]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 02 20:49:10 nnn volumio[951]: info: CoreStateMachine::stPlaybackTimer Jul 02 20:49:10 nnn volumio[951]: info: CoreStateMachine::updateTrackBlock Jul 02 20:49:10 nnn volumio[951]: info: CorePlayQueue::getTrackBlock Jul 02 20:49:10 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:10 nnn volumio[951]: info: CorePlayQueue::getTrack 0 Jul 02 20:49:10 nnn volumio[951]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 20:49:10 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:10 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:10 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:10 nnn volumio[951]: info: CoreStateMachine::serviceStop Jul 02 20:49:10 nnn volumio[951]: info: CorePlayQueue::getTrack 0 Jul 02 20:49:10 nnn volumio[951]: info: ControllerMpd::stop Jul 02 20:49:10 nnn volumio[951]: verbose: ControllerMpd::sendMpdCommand stop Jul 02 20:49:10 nnn volumio[951]: info: CorePlayQueue::clearPlayQueue Jul 02 20:49:10 nnn volumio[951]: info: CorePlayQueue::saveQueue Jul 02 20:49:10 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:10 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:10 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:10 nnn volumio[951]: info: CoreCommandRouter::volumioPushQueue Jul 02 20:49:10 nnn volumio[951]: info: Updating RAAT Signal Path Jul 02 20:49:10 nnn volumio[951]: info: Updating RAAT Signal Path Jul 02 20:49:10 nnn volumio[951]: info: Jul 02 20:49:10 nnn volumio[951]: ---------------------------- MPD announces state update: player Jul 02 20:49:10 nnn volumio[951]: info: sendMpdCommand stop took 55 milliseconds Jul 02 20:49:10 nnn volumio[951]: info: ControllerMpd::getState Jul 02 20:49:10 nnn volumio[951]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:49:10 nnn volumio[951]: info: Jul 02 20:49:10 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:10 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:10 nnn volumio[951]: info: sendMpdCommand status took 7 milliseconds Jul 02 20:49:10 nnn volumio[951]: verbose: ControllerMpd::parseState Jul 02 20:49:10 nnn volumio[951]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:49:10 nnn volumio[951]: info: Jul 02 20:49:10 nnn volumio[951]: ---------------------------- MPD announces state update: player Jul 02 20:49:10 nnn volumio[951]: info: ControllerMpd::getState Jul 02 20:49:10 nnn volumio[951]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:49:10 nnn volumio[951]: info: Jul 02 20:49:10 nnn volumio[951]: ---------------------------- MPD announces state update: player Jul 02 20:49:10 nnn volumio[951]: info: ControllerMpd::getState Jul 02 20:49:10 nnn volumio[951]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:49:10 nnn volumio[951]: error: updateQueue error: null Jul 02 20:49:10 nnn volumio[951]: info: ------------------------------ 18ms Jul 02 20:49:10 nnn volumio[951]: info: sendMpdCommand playlistinfo took 15 milliseconds Jul 02 20:49:10 nnn volumio[951]: info: sendMpdCommand status took 11 milliseconds Jul 02 20:49:10 nnn volumio[951]: info: sendMpdCommand status took 8 milliseconds Jul 02 20:49:10 nnn volumio[951]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:49:10 nnn volumio[951]: verbose: ControllerMpd::parseState Jul 02 20:49:10 nnn volumio[951]: verbose: ControllerMpd::parseState Jul 02 20:49:10 nnn volumio[951]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 02 20:49:10 nnn volumio[951]: info: ControllerMpd::pushState Jul 02 20:49:10 nnn volumio[951]: info: CoreCommandRouter::servicePushState Jul 02 20:49:10 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:10 nnn volumio[951]: info: CorePlayQueue::getTrack 0 Jul 02 20:49:10 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:10 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:10 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:10 nnn volumio[951]: info: CorePlayQueue::getTrack 0 Jul 02 20:49:10 nnn volumio[951]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 02 20:49:10 nnn volumio[951]: verbose: CURRENT POSITION 0 Jul 02 20:49:10 nnn volumio[951]: info: CoreStateMachine::syncState stateService stop Jul 02 20:49:10 nnn volumio[951]: info: CoreStateMachine::syncState currentStatus stop Jul 02 20:49:10 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:10 nnn volumio[951]: info: CorePlayQueue::getTrack 0 Jul 02 20:49:10 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:10 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:10 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:10 nnn volumio[951]: info: No code Jul 02 20:49:10 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:10 nnn volumio[951]: info: CorePlayQueue::getTrack 0 Jul 02 20:49:10 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:10 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:10 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:10 nnn volumio[951]: info: ControllerMpd::pushState Jul 02 20:49:10 nnn volumio[951]: info: CoreCommandRouter::servicePushState Jul 02 20:49:10 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:10 nnn volumio[951]: info: CorePlayQueue::getTrack 0 Jul 02 20:49:10 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:10 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:10 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:10 nnn volumio[951]: info: CorePlayQueue::getTrack 0 Jul 02 20:49:10 nnn volumio[951]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 02 20:49:10 nnn volumio[951]: verbose: CURRENT POSITION 0 Jul 02 20:49:10 nnn volumio[951]: info: CoreStateMachine::syncState stateService stop Jul 02 20:49:10 nnn volumio[951]: info: CoreStateMachine::syncState currentStatus stop Jul 02 20:49:10 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:10 nnn volumio[951]: info: CorePlayQueue::getTrack 0 Jul 02 20:49:10 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:10 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:10 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:10 nnn volumio[951]: info: No code Jul 02 20:49:10 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:10 nnn volumio[951]: info: CorePlayQueue::getTrack 0 Jul 02 20:49:10 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:10 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:10 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:10 nnn volumio[951]: info: ------------------------------ 92ms Jul 02 20:49:10 nnn volumio[951]: info: ------------------------------ 106ms Jul 02 20:49:10 nnn volumio[951]: info: ------------------------------ 105ms Jul 02 20:49:10 nnn volumio[951]: info: Jul 02 20:49:10 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:10 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:10 nnn volumio[951]: info: Jul 02 20:49:10 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:10 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:10 nnn volumio[951]: error: updateQueue error: null Jul 02 20:49:10 nnn volumio[951]: error: updateQueue error: null Jul 02 20:49:10 nnn volumio[951]: info: ------------------------------ 10ms Jul 02 20:49:10 nnn volumio[951]: info: ------------------------------ 8ms Jul 02 20:49:10 nnn volumio[951]: info: Updating RAAT Signal Path Jul 02 20:49:10 nnn volumio[951]: info: Updating RAAT Signal Path Jul 02 20:49:10 nnn volumio[951]: info: Updating RAAT Signal Path Jul 02 20:49:10 nnn volumio[951]: info: Updating RAAT Signal Path Jul 02 20:49:10 nnn volumio[951]: info: Updating RAAT Signal Path Jul 02 20:49:10 nnn volumio[951]: info: Updating RAAT Signal Path Jul 02 20:49:10 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:10 nnn volumio[951]: info: CorePlayQueue::getTrack 0 Jul 02 20:49:10 nnn volumio[951]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 20:49:10 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:10 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:10 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:10 nnn volumio[951]: error: Serial API: Failed to decode command: 100, message: undefined Jul 02 20:49:10 nnn volumio[951]: error: Serial API: Failed to decode command: MAXVOL, message: Jul 02 20:49:10 nnn volumio[951]: error: Serial API: Failed to decode command: 100, message: undefined Jul 02 20:49:10 nnn volumio[951]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Jul 02 20:49:10 nnn volumio[951]: info: Starting UPNP Playback Jul 02 20:49:10 nnn volumio[951]: info: Preparing playback through UPNP Jul 02 20:49:10 nnn volumio[951]: info: CoreCommandRouter::volumioGetState Jul 02 20:49:10 nnn volumio[951]: info: CorePlayQueue::getTrack 0 Jul 02 20:49:10 nnn volumio[951]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 02 20:49:11 nnn volumio[951]: info: Jul 02 20:49:11 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:11 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:11 nnn volumio[951]: info: Jul 02 20:49:11 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:11 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:11 nnn volumio[951]: info: Jul 02 20:49:11 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:11 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:11 nnn volumio[951]: info: ------------------------------ 12ms Jul 02 20:49:11 nnn volumio[951]: info: ------------------------------ 9ms Jul 02 20:49:11 nnn volumio[951]: info: ------------------------------ 6ms Jul 02 20:49:11 nnn volumio[951]: info: Jul 02 20:49:11 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:11 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:11 nnn volumio[951]: info: Jul 02 20:49:11 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:11 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:11 nnn volumio[951]: info: Jul 02 20:49:11 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:11 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:11 nnn volumio[951]: info: ------------------------------ 11ms Jul 02 20:49:11 nnn volumio[951]: info: ------------------------------ 8ms Jul 02 20:49:11 nnn volumio[951]: info: ------------------------------ 6ms Jul 02 20:49:11 nnn volumio[951]: info: Jul 02 20:49:11 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:11 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:11 nnn volumio[951]: info: Jul 02 20:49:11 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:11 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:11 nnn volumio[951]: info: Jul 02 20:49:11 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:11 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:11 nnn volumio[951]: info: Jul 02 20:49:11 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:11 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:11 nnn volumio[951]: info: ------------------------------ 13ms Jul 02 20:49:11 nnn volumio[951]: info: ------------------------------ 10ms Jul 02 20:49:11 nnn volumio[951]: info: ------------------------------ 8ms Jul 02 20:49:11 nnn volumio[951]: info: ------------------------------ 10ms Jul 02 20:49:11 nnn volumio[951]: info: Jul 02 20:49:11 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:11 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:11 nnn volumio[951]: info: Jul 02 20:49:11 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:11 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:11 nnn volumio[951]: info: Jul 02 20:49:11 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:11 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:11 nnn volumio[951]: info: ------------------------------ 10ms Jul 02 20:49:11 nnn volumio[951]: info: ------------------------------ 7ms Jul 02 20:49:11 nnn volumio[951]: info: Jul 02 20:49:11 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:11 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:11 nnn volumio[951]: info: Jul 02 20:49:11 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:11 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:11 nnn volumio[951]: info: ------------------------------ 13ms Jul 02 20:49:11 nnn volumio[951]: info: ------------------------------ 9ms Jul 02 20:49:11 nnn volumio[951]: info: ------------------------------ 6ms Jul 02 20:49:11 nnn volumio[951]: info: Jul 02 20:49:11 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:11 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:11 nnn volumio[951]: info: Jul 02 20:49:11 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:11 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:11 nnn volumio[951]: info: ------------------------------ 5ms Jul 02 20:49:11 nnn volumio[951]: info: Jul 02 20:49:11 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:11 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:11 nnn volumio[951]: info: ------------------------------ 9ms Jul 02 20:49:11 nnn volumio[951]: info: ------------------------------ 4ms Jul 02 20:49:11 nnn volumio[951]: info: Jul 02 20:49:11 nnn volumio[951]: ---------------------------- MPD announces state update: player Jul 02 20:49:11 nnn volumio[951]: info: ControllerMpd::getState Jul 02 20:49:11 nnn volumio[951]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:49:11 nnn volumio[951]: info: Jul 02 20:49:11 nnn volumio[951]: ---------------------------- MPD announces state update: player Jul 02 20:49:11 nnn volumio[951]: info: ControllerMpd::getState Jul 02 20:49:11 nnn volumio[951]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:49:11 nnn volumio[951]: info: Jul 02 20:49:11 nnn volumio[951]: ---------------------------- MPD announces state update: player Jul 02 20:49:11 nnn volumio[951]: info: ControllerMpd::getState Jul 02 20:49:11 nnn volumio[951]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:49:11 nnn volumio[951]: info: Jul 02 20:49:11 nnn volumio[951]: ---------------------------- MPD announces state update: player Jul 02 20:49:11 nnn volumio[951]: info: ControllerMpd::getState Jul 02 20:49:11 nnn volumio[951]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:49:11 nnn volumio[951]: info: sendMpdCommand status took 22 milliseconds Jul 02 20:49:11 nnn volumio[951]: info: sendMpdCommand status took 13 milliseconds Jul 02 20:49:11 nnn volumio[951]: info: sendMpdCommand status took 9 milliseconds Jul 02 20:49:11 nnn volumio[951]: verbose: ControllerMpd::parseState Jul 02 20:49:11 nnn volumio[951]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:49:11 nnn volumio[951]: verbose: ControllerMpd::parseState Jul 02 20:49:11 nnn volumio[951]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:49:11 nnn volumio[951]: verbose: ControllerMpd::parseState Jul 02 20:49:11 nnn volumio[951]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:49:11 nnn volumio[951]: info: Jul 02 20:49:11 nnn volumio[951]: ---------------------------- MPD announces state update: player Jul 02 20:49:11 nnn volumio[951]: info: ControllerMpd::getState Jul 02 20:49:11 nnn volumio[951]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:49:11 nnn volumio[951]: info: Jul 02 20:49:11 nnn volumio[951]: ---------------------------- MPD announces state update: player Jul 02 20:49:11 nnn volumio[951]: info: ControllerMpd::getState Jul 02 20:49:11 nnn volumio[951]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:49:11 nnn volumio[951]: info: sendMpdCommand status took 36 milliseconds Jul 02 20:49:11 nnn volumio[951]: info: sendMpdCommand playlistinfo took 29 milliseconds Jul 02 20:49:11 nnn volumio[951]: info: sendMpdCommand playlistinfo took 29 milliseconds Jul 02 20:49:11 nnn volumio[951]: info: sendMpdCommand playlistinfo took 28 milliseconds Jul 02 20:49:11 nnn volumio[951]: info: sendMpdCommand status took 19 milliseconds Jul 02 20:49:11 nnn volumio[951]: info: sendMpdCommand status took 14 milliseconds Jul 02 20:49:11 nnn volumio[951]: verbose: ControllerMpd::parseState Jul 02 20:49:11 nnn volumio[951]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:49:11 nnn volumio[951]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:49:11 nnn volumio[951]: verbose: ControllerMpd::parseState Jul 02 20:49:11 nnn volumio[951]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:49:11 nnn volumio[951]: verbose: ControllerMpd::parseState Jul 02 20:49:11 nnn volumio[951]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:49:11 nnn volumio[951]: info: ControllerMpd::pushState Jul 02 20:49:11 nnn volumio[951]: info: CoreCommandRouter::servicePushState Jul 02 20:49:11 nnn volumio[951]: verbose: In UPNP mode Jul 02 20:49:11 nnn volumio[951]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":133,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Perhaps, Perhaps, Perhaps","artist":"Stéphane Pompougnac","album":"Hôtel Costes, Vol. 14","uri":"http://m701.music.126.net/20250702211409/f9aa8befa0408ac1cb00f3dc750c60fe/jdyyaac/050f/0352/0f58/2e1c029835488f92e1b182485a015d99.m4a?vuutv=4HNYNljSJDCDHCRHhk3rStncVUCWngCqDZnWHD2iqvpqrjKslt1/3UHuTnTZYbXx5JxcoerEHQFGYKkMtNckvl+A57rDebtka22yJtAIKm8=","trackType":""} Jul 02 20:49:11 nnn volumio[951]: verbose: CURRENT POSITION 0 Jul 02 20:49:11 nnn volumio[951]: info: CoreStateMachine::syncState stateService play Jul 02 20:49:11 nnn volumio[951]: info: CoreStateMachine::syncState currentStatus stop Jul 02 20:49:11 nnn volumio[951]: info: ControllerMpd::pushState Jul 02 20:49:11 nnn volumio[951]: info: CoreCommandRouter::servicePushState Jul 02 20:49:11 nnn volumio[951]: verbose: In UPNP mode Jul 02 20:49:11 nnn volumio[951]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":133,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Perhaps, Perhaps, Perhaps","artist":"Stéphane Pompougnac","album":"Hôtel Costes, Vol. 14","uri":"http://m701.music.126.net/20250702211409/f9aa8befa0408ac1cb00f3dc750c60fe/jdyyaac/050f/0352/0f58/2e1c029835488f92e1b182485a015d99.m4a?vuutv=4HNYNljSJDCDHCRHhk3rStncVUCWngCqDZnWHD2iqvpqrjKslt1/3UHuTnTZYbXx5JxcoerEHQFGYKkMtNckvl+A57rDebtka22yJtAIKm8=","trackType":""} Jul 02 20:49:11 nnn volumio[951]: verbose: CURRENT POSITION 0 Jul 02 20:49:11 nnn volumio[951]: info: CoreStateMachine::syncState stateService play Jul 02 20:49:11 nnn volumio[951]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:49:11 nnn volumio[951]: info: Received an update from plugin. extracting info from payload Jul 02 20:49:11 nnn volumio[951]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 02 20:49:11 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:11 nnn volumio[951]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 20:49:11 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:12 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:12 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:12 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:12 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:12 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:12 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:12 nnn volumio[951]: info: ControllerMpd::pushState Jul 02 20:49:12 nnn volumio[951]: info: CoreCommandRouter::servicePushState Jul 02 20:49:12 nnn volumio[951]: verbose: In UPNP mode Jul 02 20:49:12 nnn volumio[951]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":133,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Perhaps, Perhaps, Perhaps","artist":"Stéphane Pompougnac","album":"Hôtel Costes, Vol. 14","uri":"http://m701.music.126.net/20250702211409/f9aa8befa0408ac1cb00f3dc750c60fe/jdyyaac/050f/0352/0f58/2e1c029835488f92e1b182485a015d99.m4a?vuutv=4HNYNljSJDCDHCRHhk3rStncVUCWngCqDZnWHD2iqvpqrjKslt1/3UHuTnTZYbXx5JxcoerEHQFGYKkMtNckvl+A57rDebtka22yJtAIKm8=","trackType":""} Jul 02 20:49:12 nnn volumio[951]: verbose: CURRENT POSITION 0 Jul 02 20:49:12 nnn volumio[951]: info: CoreStateMachine::syncState stateService play Jul 02 20:49:12 nnn volumio[951]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:49:12 nnn volumio[951]: info: Received an update from plugin. extracting info from payload Jul 02 20:49:12 nnn volumio[951]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 02 20:49:12 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:12 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:12 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:12 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:12 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:12 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:12 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:12 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:12 nnn volumio[951]: info: ------------------------------ 169ms Jul 02 20:49:12 nnn volumio[951]: info: ------------------------------ 221ms Jul 02 20:49:12 nnn volumio[951]: info: ------------------------------ 218ms Jul 02 20:49:12 nnn volumio[951]: info: sendMpdCommand playlistinfo took 172 milliseconds Jul 02 20:49:12 nnn volumio[951]: info: sendMpdCommand playlistinfo took 167 milliseconds Jul 02 20:49:12 nnn volumio[951]: info: sendMpdCommand playlistinfo took 166 milliseconds Jul 02 20:49:12 nnn volumio[951]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:49:12 nnn volumio[951]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:49:12 nnn volumio[951]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:49:12 nnn volumio[951]: info: ControllerMpd::pushState Jul 02 20:49:12 nnn volumio[951]: info: CoreCommandRouter::servicePushState Jul 02 20:49:12 nnn volumio[951]: verbose: In UPNP mode Jul 02 20:49:12 nnn volumio[951]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":133,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Perhaps, Perhaps, Perhaps","artist":"Stéphane Pompougnac","album":"Hôtel Costes, Vol. 14","uri":"http://m701.music.126.net/20250702211409/f9aa8befa0408ac1cb00f3dc750c60fe/jdyyaac/050f/0352/0f58/2e1c029835488f92e1b182485a015d99.m4a?vuutv=4HNYNljSJDCDHCRHhk3rStncVUCWngCqDZnWHD2iqvpqrjKslt1/3UHuTnTZYbXx5JxcoerEHQFGYKkMtNckvl+A57rDebtka22yJtAIKm8=","trackType":""} Jul 02 20:49:12 nnn volumio[951]: verbose: CURRENT POSITION 0 Jul 02 20:49:12 nnn volumio[951]: info: CoreStateMachine::syncState stateService play Jul 02 20:49:12 nnn volumio[951]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:49:12 nnn volumio[951]: info: Received an update from plugin. extracting info from payload Jul 02 20:49:12 nnn volumio[951]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 02 20:49:12 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:12 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:12 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:12 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:12 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:12 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:12 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:12 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:12 nnn volumio[951]: info: ControllerMpd::pushState Jul 02 20:49:12 nnn volumio[951]: info: CoreCommandRouter::servicePushState Jul 02 20:49:12 nnn volumio[951]: verbose: In UPNP mode Jul 02 20:49:12 nnn volumio[951]: verbose: STATE SERVICE {"status":"play","position":0,"seek":480,"duration":133,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Perhaps, Perhaps, Perhaps","artist":"Stéphane Pompougnac","album":"Hôtel Costes, Vol. 14","uri":"http://m701.music.126.net/20250702211409/f9aa8befa0408ac1cb00f3dc750c60fe/jdyyaac/050f/0352/0f58/2e1c029835488f92e1b182485a015d99.m4a?vuutv=4HNYNljSJDCDHCRHhk3rStncVUCWngCqDZnWHD2iqvpqrjKslt1/3UHuTnTZYbXx5JxcoerEHQFGYKkMtNckvl+A57rDebtka22yJtAIKm8=","trackType":""} Jul 02 20:49:12 nnn volumio[951]: verbose: CURRENT POSITION 0 Jul 02 20:49:12 nnn volumio[951]: info: CoreStateMachine::syncState stateService play Jul 02 20:49:12 nnn volumio[951]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:49:12 nnn volumio[951]: info: Received an update from plugin. extracting info from payload Jul 02 20:49:12 nnn volumio[951]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 02 20:49:12 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:12 nnn volumio[951]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 20:49:12 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:12 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:12 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:12 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:12 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:12 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:12 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:12 nnn volumio[951]: info: ControllerMpd::pushState Jul 02 20:49:12 nnn volumio[951]: info: CoreCommandRouter::servicePushState Jul 02 20:49:12 nnn volumio[951]: verbose: In UPNP mode Jul 02 20:49:12 nnn volumio[951]: verbose: STATE SERVICE {"status":"play","position":0,"seek":480,"duration":133,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Perhaps, Perhaps, Perhaps","artist":"Stéphane Pompougnac","album":"Hôtel Costes, Vol. 14","uri":"http://m701.music.126.net/20250702211409/f9aa8befa0408ac1cb00f3dc750c60fe/jdyyaac/050f/0352/0f58/2e1c029835488f92e1b182485a015d99.m4a?vuutv=4HNYNljSJDCDHCRHhk3rStncVUCWngCqDZnWHD2iqvpqrjKslt1/3UHuTnTZYbXx5JxcoerEHQFGYKkMtNckvl+A57rDebtka22yJtAIKm8=","trackType":""} Jul 02 20:49:12 nnn volumio[951]: verbose: CURRENT POSITION 0 Jul 02 20:49:12 nnn volumio[951]: info: CoreStateMachine::syncState stateService play Jul 02 20:49:12 nnn volumio[951]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:49:12 nnn volumio[951]: info: Received an update from plugin. extracting info from payload Jul 02 20:49:12 nnn volumio[951]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 02 20:49:12 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:12 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:12 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:12 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:12 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:12 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:12 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:12 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:12 nnn volumio[951]: info: ------------------------------ 358ms Jul 02 20:49:12 nnn volumio[951]: info: ------------------------------ 340ms Jul 02 20:49:12 nnn volumio[951]: info: ------------------------------ 335ms Jul 02 20:49:12 nnn volumio[951]: info: CoreCommandRouter::volumioGetQueue Jul 02 20:49:12 nnn volumio[951]: info: CoreStateMachine::getQueue Jul 02 20:49:12 nnn volumio[951]: info: CorePlayQueue::getQueue Jul 02 20:49:12 nnn volumio[951]: info: CoreCommandRouter::volumioGetQueue Jul 02 20:49:12 nnn volumio[951]: info: CoreStateMachine::getQueue Jul 02 20:49:12 nnn volumio[951]: info: CorePlayQueue::getQueue Jul 02 20:49:12 nnn volumio[951]: info: CoreCommandRouter::volumioGetQueue Jul 02 20:49:12 nnn volumio[951]: info: CoreStateMachine::getQueue Jul 02 20:49:12 nnn volumio[951]: info: CorePlayQueue::getQueue Jul 02 20:49:12 nnn volumio[951]: info: CoreCommandRouter::volumioGetQueue Jul 02 20:49:12 nnn volumio[951]: info: CoreStateMachine::getQueue Jul 02 20:49:12 nnn volumio[951]: info: CorePlayQueue::getQueue Jul 02 20:49:12 nnn volumio[951]: info: CoreCommandRouter::volumioGetQueue Jul 02 20:49:12 nnn volumio[951]: info: CoreStateMachine::getQueue Jul 02 20:49:12 nnn volumio[951]: info: CorePlayQueue::getQueue Jul 02 20:49:12 nnn volumio[951]: info: CoreCommandRouter::volumioGetQueue Jul 02 20:49:12 nnn volumio[951]: info: CoreStateMachine::getQueue Jul 02 20:49:12 nnn volumio[951]: info: CorePlayQueue::getQueue Jul 02 20:49:12 nnn volumio[951]: info: CoreCommandRouter::volumioGetQueue Jul 02 20:49:12 nnn volumio[951]: info: CoreStateMachine::getQueue Jul 02 20:49:12 nnn volumio[951]: info: CorePlayQueue::getQueue Jul 02 20:49:12 nnn volumio[951]: info: CoreCommandRouter::volumioGetQueue Jul 02 20:49:12 nnn volumio[951]: info: CoreStateMachine::getQueue Jul 02 20:49:12 nnn volumio[951]: info: CorePlayQueue::getQueue Jul 02 20:49:12 nnn volumio[951]: info: CoreCommandRouter::volumioGetQueue Jul 02 20:49:12 nnn volumio[951]: info: CoreStateMachine::getQueue Jul 02 20:49:12 nnn volumio[951]: info: CorePlayQueue::getQueue Jul 02 20:49:12 nnn volumio[951]: info: CoreCommandRouter::volumioGetQueue Jul 02 20:49:12 nnn volumio[951]: info: CoreStateMachine::getQueue Jul 02 20:49:12 nnn volumio[951]: info: CorePlayQueue::getQueue Jul 02 20:49:12 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:12 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:12 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:12 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:12 nnn volumio[951]: error: Serial API: Failed to decode command: 100, message: undefined Jul 02 20:49:12 nnn volumio[951]: error: Serial API: Failed to decode command: MAXVOL, message: Jul 02 20:49:12 nnn volumio[951]: error: Serial API: Failed to decode command: 100, message: undefined Jul 02 20:49:12 nnn volumio[951]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Jul 02 20:49:12 nnn volumio[951]: info: CoreCommandRouter::volumioGetQueue Jul 02 20:49:12 nnn volumio[951]: info: CoreStateMachine::getQueue Jul 02 20:49:12 nnn volumio[951]: info: CorePlayQueue::getQueue Jul 02 20:49:15 nnn volumio[1229]: 5 Jul 02 20:49:15 nnn volumio[1229]: % Total % Received % Xferd Average Speed Time Time Time Current Jul 02 20:49:15 nnn volumio[1229]: Dload Upload Total Spent Left Speed Jul 02 20:49:15 nnn volumio[951]: info: Jul 02 20:49:15 nnn volumio[951]: ---------------------------- Client requests Volume 5 Jul 02 20:49:15 nnn volumio[951]: info: VolumeController::SetAlsaVolume5 Jul 02 20:49:15 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:15 nnn volumio[951]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 20:49:15 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:15 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:15 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:15 nnn volumio[1229]: [158B blob data] Jul 02 20:49:15 nnn volumio[1229]: {"time":1751460555118,"response":"volume Success"}5 Jul 02 20:49:15 nnn volumio[951]: info: CoreCommandRouter::volumioGetQueue Jul 02 20:49:15 nnn volumio[951]: info: CoreStateMachine::getQueue Jul 02 20:49:15 nnn volumio[951]: info: CorePlayQueue::getQueue Jul 02 20:49:15 nnn volumio[951]: info: Updating RAAT Signal Path Jul 02 20:49:17 nnn volumio[951]: info: Clearing queue after UPNP request Jul 02 20:49:17 nnn volumio[951]: info: CoreStateMachine::ClearQueue Jul 02 20:49:17 nnn volumio[951]: info: CoreStateMachine::stop Jul 02 20:49:17 nnn volumio[951]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 02 20:49:17 nnn volumio[951]: info: CoreStateMachine::stPlaybackTimer Jul 02 20:49:17 nnn volumio[951]: info: CoreStateMachine::updateTrackBlock Jul 02 20:49:17 nnn volumio[951]: info: CorePlayQueue::getTrackBlock Jul 02 20:49:17 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:17 nnn volumio[951]: info: CorePlayQueue::getTrack 0 Jul 02 20:49:17 nnn volumio[951]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 20:49:17 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:17 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:17 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:17 nnn volumio[951]: info: CoreStateMachine::serviceStop Jul 02 20:49:17 nnn volumio[951]: info: CorePlayQueue::getTrack 0 Jul 02 20:49:17 nnn volumio[951]: info: ControllerMpd::stop Jul 02 20:49:17 nnn volumio[951]: verbose: ControllerMpd::sendMpdCommand stop Jul 02 20:49:17 nnn volumio[951]: info: CorePlayQueue::clearPlayQueue Jul 02 20:49:17 nnn volumio[951]: info: CorePlayQueue::saveQueue Jul 02 20:49:17 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:17 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:17 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:17 nnn volumio[951]: info: CoreCommandRouter::volumioPushQueue Jul 02 20:49:17 nnn volumio[951]: info: Updating RAAT Signal Path Jul 02 20:49:17 nnn volumio[951]: info: Updating RAAT Signal Path Jul 02 20:49:17 nnn volumio[951]: info: Jul 02 20:49:17 nnn volumio[951]: ---------------------------- MPD announces state update: player Jul 02 20:49:17 nnn volumio[951]: info: sendMpdCommand stop took 55 milliseconds Jul 02 20:49:17 nnn volumio[951]: info: ControllerMpd::getState Jul 02 20:49:17 nnn volumio[951]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:49:17 nnn volumio[951]: info: Jul 02 20:49:17 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:17 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:17 nnn volumio[951]: info: sendMpdCommand status took 5 milliseconds Jul 02 20:49:17 nnn volumio[951]: verbose: ControllerMpd::parseState Jul 02 20:49:17 nnn volumio[951]: info: ControllerMpd::pushState Jul 02 20:49:17 nnn volumio[951]: info: CoreCommandRouter::servicePushState Jul 02 20:49:17 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:17 nnn volumio[951]: info: CorePlayQueue::getTrack 0 Jul 02 20:49:17 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:17 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:17 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:17 nnn volumio[951]: info: CorePlayQueue::getTrack 0 Jul 02 20:49:17 nnn volumio[951]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 02 20:49:17 nnn volumio[951]: verbose: CURRENT POSITION 0 Jul 02 20:49:17 nnn volumio[951]: info: CoreStateMachine::syncState stateService stop Jul 02 20:49:17 nnn volumio[951]: info: CoreStateMachine::syncState currentStatus stop Jul 02 20:49:17 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:17 nnn volumio[951]: info: CorePlayQueue::getTrack 0 Jul 02 20:49:17 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:17 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:17 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:17 nnn volumio[951]: info: No code Jul 02 20:49:17 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:17 nnn volumio[951]: info: CorePlayQueue::getTrack 0 Jul 02 20:49:17 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:17 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:17 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:17 nnn volumio[951]: info: ------------------------------ 54ms Jul 02 20:49:17 nnn volumio[951]: info: Jul 02 20:49:17 nnn volumio[951]: ---------------------------- MPD announces state update: player Jul 02 20:49:17 nnn volumio[951]: info: ControllerMpd::getState Jul 02 20:49:17 nnn volumio[951]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:49:17 nnn volumio[951]: info: Jul 02 20:49:17 nnn volumio[951]: ---------------------------- MPD announces state update: player Jul 02 20:49:17 nnn volumio[951]: info: ControllerMpd::getState Jul 02 20:49:17 nnn volumio[951]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:49:17 nnn volumio[951]: error: updateQueue error: null Jul 02 20:49:17 nnn volumio[951]: info: ------------------------------ 63ms Jul 02 20:49:17 nnn volumio[951]: info: sendMpdCommand status took 10 milliseconds Jul 02 20:49:17 nnn volumio[951]: info: sendMpdCommand status took 7 milliseconds Jul 02 20:49:17 nnn volumio[951]: verbose: ControllerMpd::parseState Jul 02 20:49:17 nnn volumio[951]: verbose: ControllerMpd::parseState Jul 02 20:49:17 nnn volumio[951]: info: ControllerMpd::pushState Jul 02 20:49:17 nnn volumio[951]: info: CoreCommandRouter::servicePushState Jul 02 20:49:17 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:17 nnn volumio[951]: info: CorePlayQueue::getTrack 0 Jul 02 20:49:17 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:17 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:17 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:17 nnn volumio[951]: info: CorePlayQueue::getTrack 0 Jul 02 20:49:17 nnn volumio[951]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 02 20:49:17 nnn volumio[951]: verbose: CURRENT POSITION 0 Jul 02 20:49:17 nnn volumio[951]: info: CoreStateMachine::syncState stateService stop Jul 02 20:49:17 nnn volumio[951]: info: CoreStateMachine::syncState currentStatus stop Jul 02 20:49:17 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:17 nnn volumio[951]: info: CorePlayQueue::getTrack 0 Jul 02 20:49:17 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:17 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:17 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:17 nnn volumio[951]: info: No code Jul 02 20:49:17 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:17 nnn volumio[951]: info: CorePlayQueue::getTrack 0 Jul 02 20:49:17 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:17 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:17 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:17 nnn volumio[951]: info: ControllerMpd::pushState Jul 02 20:49:17 nnn volumio[951]: info: CoreCommandRouter::servicePushState Jul 02 20:49:17 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:17 nnn volumio[951]: info: CorePlayQueue::getTrack 0 Jul 02 20:49:17 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:17 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:17 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:17 nnn volumio[951]: info: CorePlayQueue::getTrack 0 Jul 02 20:49:17 nnn volumio[951]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 02 20:49:17 nnn volumio[951]: verbose: CURRENT POSITION 0 Jul 02 20:49:17 nnn volumio[951]: info: CoreStateMachine::syncState stateService stop Jul 02 20:49:17 nnn volumio[951]: info: CoreStateMachine::syncState currentStatus stop Jul 02 20:49:17 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:17 nnn volumio[951]: info: CorePlayQueue::getTrack 0 Jul 02 20:49:17 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:17 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:17 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:17 nnn volumio[951]: info: No code Jul 02 20:49:17 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:17 nnn volumio[951]: info: CorePlayQueue::getTrack 0 Jul 02 20:49:17 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:17 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:17 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:17 nnn volumio[951]: info: ------------------------------ 98ms Jul 02 20:49:17 nnn volumio[951]: info: ------------------------------ 96ms Jul 02 20:49:17 nnn volumio[951]: info: Jul 02 20:49:17 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:17 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:17 nnn volumio[951]: info: Jul 02 20:49:17 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:17 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:17 nnn volumio[951]: info: Updating RAAT Signal Path Jul 02 20:49:17 nnn volumio[951]: info: Updating RAAT Signal Path Jul 02 20:49:17 nnn volumio[951]: info: Updating RAAT Signal Path Jul 02 20:49:17 nnn volumio[951]: info: Updating RAAT Signal Path Jul 02 20:49:17 nnn volumio[951]: info: Updating RAAT Signal Path Jul 02 20:49:17 nnn volumio[951]: info: Updating RAAT Signal Path Jul 02 20:49:17 nnn volumio[951]: info: Updating RAAT Signal Path Jul 02 20:49:17 nnn volumio[951]: info: Updating RAAT Signal Path Jul 02 20:49:17 nnn volumio[951]: info: Updating RAAT Signal Path Jul 02 20:49:17 nnn volumio[951]: error: updateQueue error: null Jul 02 20:49:17 nnn volumio[951]: error: updateQueue error: null Jul 02 20:49:17 nnn volumio[951]: info: ------------------------------ 76ms Jul 02 20:49:17 nnn volumio[951]: info: ------------------------------ 75ms Jul 02 20:49:17 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:17 nnn volumio[951]: info: CorePlayQueue::getTrack 0 Jul 02 20:49:17 nnn volumio[951]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 20:49:17 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:17 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:17 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:17 nnn volumio[951]: error: Serial API: Failed to decode command: 100, message: undefined Jul 02 20:49:17 nnn volumio[951]: error: Serial API: Failed to decode command: MAXVOL, message: Jul 02 20:49:17 nnn volumio[951]: error: Serial API: Failed to decode command: 100, message: undefined Jul 02 20:49:17 nnn volumio[951]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Jul 02 20:49:18 nnn volumio[951]: info: Starting UPNP Playback Jul 02 20:49:18 nnn volumio[951]: info: Preparing playback through UPNP Jul 02 20:49:18 nnn volumio[951]: info: CoreCommandRouter::volumioGetState Jul 02 20:49:18 nnn volumio[951]: info: CorePlayQueue::getTrack 0 Jul 02 20:49:18 nnn volumio[951]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 02 20:49:18 nnn volumio[951]: info: Jul 02 20:49:18 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:18 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:18 nnn volumio[951]: info: Jul 02 20:49:18 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:18 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:18 nnn volumio[951]: info: Jul 02 20:49:18 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:18 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:18 nnn volumio[951]: info: ------------------------------ 10ms Jul 02 20:49:18 nnn volumio[951]: info: ------------------------------ 8ms Jul 02 20:49:18 nnn volumio[951]: info: ------------------------------ 5ms Jul 02 20:49:18 nnn volumio[951]: info: Jul 02 20:49:18 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:18 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:18 nnn volumio[951]: info: Jul 02 20:49:18 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:18 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:18 nnn volumio[951]: info: Jul 02 20:49:18 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:18 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:18 nnn volumio[951]: info: Jul 02 20:49:18 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:18 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:18 nnn volumio[951]: info: ------------------------------ 12ms Jul 02 20:49:18 nnn volumio[951]: info: ------------------------------ 10ms Jul 02 20:49:18 nnn volumio[951]: info: ------------------------------ 7ms Jul 02 20:49:18 nnn volumio[951]: info: Jul 02 20:49:18 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:18 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:18 nnn volumio[951]: info: ------------------------------ 11ms Jul 02 20:49:18 nnn volumio[951]: info: ------------------------------ 7ms Jul 02 20:49:18 nnn volumio[951]: info: Jul 02 20:49:18 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:18 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:18 nnn volumio[951]: info: Jul 02 20:49:18 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:18 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:18 nnn volumio[951]: info: ------------------------------ 7ms Jul 02 20:49:18 nnn volumio[951]: info: ------------------------------ 5ms Jul 02 20:49:18 nnn volumio[951]: info: Jul 02 20:49:18 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:18 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:18 nnn volumio[951]: info: Jul 02 20:49:18 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:18 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:18 nnn volumio[951]: info: Jul 02 20:49:18 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:18 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:18 nnn volumio[951]: info: ------------------------------ 12ms Jul 02 20:49:18 nnn volumio[951]: info: ------------------------------ 10ms Jul 02 20:49:18 nnn volumio[951]: info: Jul 02 20:49:18 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:18 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:18 nnn volumio[951]: info: Jul 02 20:49:18 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:18 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:18 nnn volumio[951]: info: ------------------------------ 16ms Jul 02 20:49:18 nnn volumio[951]: info: ------------------------------ 11ms Jul 02 20:49:18 nnn volumio[951]: info: ------------------------------ 9ms Jul 02 20:49:18 nnn volumio[951]: info: Jul 02 20:49:18 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:18 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:18 nnn volumio[951]: info: Jul 02 20:49:18 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:18 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:18 nnn volumio[951]: info: Jul 02 20:49:18 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:18 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:18 nnn volumio[951]: info: ------------------------------ 16ms Jul 02 20:49:18 nnn volumio[951]: info: ------------------------------ 13ms Jul 02 20:49:18 nnn volumio[951]: info: ------------------------------ 10ms Jul 02 20:49:18 nnn volumio[951]: info: Jul 02 20:49:18 nnn volumio[951]: ---------------------------- MPD announces state update: player Jul 02 20:49:18 nnn volumio[951]: info: ControllerMpd::getState Jul 02 20:49:18 nnn volumio[951]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:49:18 nnn volumio[951]: info: Jul 02 20:49:18 nnn volumio[951]: ---------------------------- MPD announces state update: player Jul 02 20:49:18 nnn volumio[951]: info: ControllerMpd::getState Jul 02 20:49:18 nnn volumio[951]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:49:18 nnn volumio[951]: info: Jul 02 20:49:18 nnn volumio[951]: ---------------------------- MPD announces state update: player Jul 02 20:49:18 nnn volumio[951]: info: ControllerMpd::getState Jul 02 20:49:18 nnn volumio[951]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:49:18 nnn volumio[951]: info: Jul 02 20:49:18 nnn volumio[951]: ---------------------------- MPD announces state update: player Jul 02 20:49:18 nnn volumio[951]: info: ControllerMpd::getState Jul 02 20:49:18 nnn volumio[951]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:49:18 nnn volumio[951]: info: sendMpdCommand status took 19 milliseconds Jul 02 20:49:18 nnn volumio[951]: info: sendMpdCommand status took 16 milliseconds Jul 02 20:49:18 nnn volumio[951]: info: sendMpdCommand status took 11 milliseconds Jul 02 20:49:18 nnn volumio[951]: verbose: ControllerMpd::parseState Jul 02 20:49:18 nnn volumio[951]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:49:18 nnn volumio[951]: verbose: ControllerMpd::parseState Jul 02 20:49:18 nnn volumio[951]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:49:18 nnn volumio[951]: verbose: ControllerMpd::parseState Jul 02 20:49:19 nnn volumio[951]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:49:19 nnn volumio[951]: info: Jul 02 20:49:19 nnn volumio[951]: ---------------------------- MPD announces state update: player Jul 02 20:49:19 nnn volumio[951]: info: ControllerMpd::getState Jul 02 20:49:19 nnn volumio[951]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:49:19 nnn volumio[951]: info: Jul 02 20:49:19 nnn volumio[951]: ---------------------------- MPD announces state update: player Jul 02 20:49:19 nnn volumio[951]: info: ControllerMpd::getState Jul 02 20:49:19 nnn volumio[951]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:49:19 nnn volumio[951]: info: sendMpdCommand status took 29 milliseconds Jul 02 20:49:19 nnn volumio[951]: info: sendMpdCommand playlistinfo took 24 milliseconds Jul 02 20:49:19 nnn volumio[951]: info: sendMpdCommand playlistinfo took 24 milliseconds Jul 02 20:49:19 nnn volumio[951]: info: sendMpdCommand playlistinfo took 23 milliseconds Jul 02 20:49:19 nnn volumio[951]: info: sendMpdCommand status took 18 milliseconds Jul 02 20:49:19 nnn volumio[951]: info: sendMpdCommand status took 13 milliseconds Jul 02 20:49:19 nnn volumio[951]: verbose: ControllerMpd::parseState Jul 02 20:49:19 nnn volumio[951]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:49:19 nnn volumio[951]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:49:19 nnn volumio[951]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:49:19 nnn volumio[951]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:49:19 nnn volumio[951]: verbose: ControllerMpd::parseState Jul 02 20:49:19 nnn volumio[951]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:49:19 nnn volumio[951]: verbose: ControllerMpd::parseState Jul 02 20:49:19 nnn volumio[951]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:49:19 nnn volumio[951]: info: ControllerMpd::pushState Jul 02 20:49:19 nnn volumio[951]: info: CoreCommandRouter::servicePushState Jul 02 20:49:19 nnn volumio[951]: verbose: In UPNP mode Jul 02 20:49:19 nnn volumio[951]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":376,"samplerate":"12 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Love Won't Sleep","artist":"Lostboycrow","album":"Love Won't Sleep","uri":"http://m8.music.126.net/20250702211417/c1ba97e5d8682afbb148caf59ca533b8/yyaac/obj/wonDkMOGw6XDiTHCmMOi/2061205332/7bce/2e61/5f6c/8edc3984316e08ca82dd998f129d580b.m4a?vuutv=XEK0TsWFBM2GMt+zIBJkdIpOKV93N2wB50R3RrevYBwgUjmPRdZddMkscrXNCwO/dmd+Hblnj4I9HQg5dwYxIWrbMdJx4xTk7/BbDGvFNlo=","trackType":""} Jul 02 20:49:19 nnn volumio[951]: verbose: CURRENT POSITION 0 Jul 02 20:49:19 nnn volumio[951]: info: CoreStateMachine::syncState stateService play Jul 02 20:49:19 nnn volumio[951]: info: CoreStateMachine::syncState currentStatus stop Jul 02 20:49:19 nnn volumio[951]: info: ControllerMpd::pushState Jul 02 20:49:19 nnn volumio[951]: info: CoreCommandRouter::servicePushState Jul 02 20:49:19 nnn volumio[951]: verbose: In UPNP mode Jul 02 20:49:19 nnn volumio[951]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":376,"samplerate":"12 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Love Won't Sleep","artist":"Lostboycrow","album":"Love Won't Sleep","uri":"http://m8.music.126.net/20250702211417/c1ba97e5d8682afbb148caf59ca533b8/yyaac/obj/wonDkMOGw6XDiTHCmMOi/2061205332/7bce/2e61/5f6c/8edc3984316e08ca82dd998f129d580b.m4a?vuutv=XEK0TsWFBM2GMt+zIBJkdIpOKV93N2wB50R3RrevYBwgUjmPRdZddMkscrXNCwO/dmd+Hblnj4I9HQg5dwYxIWrbMdJx4xTk7/BbDGvFNlo=","trackType":""} Jul 02 20:49:19 nnn volumio[951]: verbose: CURRENT POSITION 0 Jul 02 20:49:19 nnn volumio[951]: info: CoreStateMachine::syncState stateService play Jul 02 20:49:19 nnn volumio[951]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:49:19 nnn volumio[951]: info: Received an update from plugin. extracting info from payload Jul 02 20:49:19 nnn volumio[951]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 02 20:49:19 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:19 nnn volumio[951]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 20:49:19 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:19 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:19 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:19 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:19 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:19 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:19 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:19 nnn volumio[951]: info: ControllerMpd::pushState Jul 02 20:49:19 nnn volumio[951]: info: CoreCommandRouter::servicePushState Jul 02 20:49:19 nnn volumio[951]: verbose: In UPNP mode Jul 02 20:49:19 nnn volumio[951]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":376,"samplerate":"12 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Love Won't Sleep","artist":"Lostboycrow","album":"Love Won't Sleep","uri":"http://m8.music.126.net/20250702211417/c1ba97e5d8682afbb148caf59ca533b8/yyaac/obj/wonDkMOGw6XDiTHCmMOi/2061205332/7bce/2e61/5f6c/8edc3984316e08ca82dd998f129d580b.m4a?vuutv=XEK0TsWFBM2GMt+zIBJkdIpOKV93N2wB50R3RrevYBwgUjmPRdZddMkscrXNCwO/dmd+Hblnj4I9HQg5dwYxIWrbMdJx4xTk7/BbDGvFNlo=","trackType":""} Jul 02 20:49:19 nnn volumio[951]: verbose: CURRENT POSITION 0 Jul 02 20:49:19 nnn volumio[951]: info: CoreStateMachine::syncState stateService play Jul 02 20:49:19 nnn volumio[951]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:49:19 nnn volumio[951]: info: Received an update from plugin. extracting info from payload Jul 02 20:49:19 nnn volumio[951]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 02 20:49:19 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:19 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:19 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:19 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:19 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:19 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:19 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:19 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:19 nnn volumio[951]: info: ------------------------------ 135ms Jul 02 20:49:19 nnn volumio[951]: info: ------------------------------ 177ms Jul 02 20:49:19 nnn volumio[951]: info: ------------------------------ 173ms Jul 02 20:49:19 nnn volumio[951]: info: sendMpdCommand playlistinfo took 129 milliseconds Jul 02 20:49:19 nnn volumio[951]: info: sendMpdCommand playlistinfo took 125 milliseconds Jul 02 20:49:19 nnn volumio[951]: info: sendMpdCommand playlistinfo took 124 milliseconds Jul 02 20:49:19 nnn volumio[951]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:49:19 nnn volumio[951]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:49:19 nnn volumio[951]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:49:19 nnn volumio[951]: info: ControllerMpd::pushState Jul 02 20:49:19 nnn volumio[951]: info: CoreCommandRouter::servicePushState Jul 02 20:49:19 nnn volumio[951]: verbose: In UPNP mode Jul 02 20:49:19 nnn volumio[951]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":376,"samplerate":"12 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Love Won't Sleep","artist":"Lostboycrow","album":"Love Won't Sleep","uri":"http://m8.music.126.net/20250702211417/c1ba97e5d8682afbb148caf59ca533b8/yyaac/obj/wonDkMOGw6XDiTHCmMOi/2061205332/7bce/2e61/5f6c/8edc3984316e08ca82dd998f129d580b.m4a?vuutv=XEK0TsWFBM2GMt+zIBJkdIpOKV93N2wB50R3RrevYBwgUjmPRdZddMkscrXNCwO/dmd+Hblnj4I9HQg5dwYxIWrbMdJx4xTk7/BbDGvFNlo=","trackType":""} Jul 02 20:49:19 nnn volumio[951]: verbose: CURRENT POSITION 0 Jul 02 20:49:19 nnn volumio[951]: info: CoreStateMachine::syncState stateService play Jul 02 20:49:19 nnn volumio[951]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:49:19 nnn volumio[951]: info: Received an update from plugin. extracting info from payload Jul 02 20:49:19 nnn volumio[951]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 02 20:49:19 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:19 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:19 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:19 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:19 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:19 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:19 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:19 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:19 nnn volumio[951]: info: ControllerMpd::pushState Jul 02 20:49:19 nnn volumio[951]: info: CoreCommandRouter::servicePushState Jul 02 20:49:19 nnn volumio[951]: verbose: In UPNP mode Jul 02 20:49:19 nnn volumio[951]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":376,"samplerate":"12 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Love Won't Sleep","artist":"Lostboycrow","album":"Love Won't Sleep","uri":"http://m8.music.126.net/20250702211417/c1ba97e5d8682afbb148caf59ca533b8/yyaac/obj/wonDkMOGw6XDiTHCmMOi/2061205332/7bce/2e61/5f6c/8edc3984316e08ca82dd998f129d580b.m4a?vuutv=XEK0TsWFBM2GMt+zIBJkdIpOKV93N2wB50R3RrevYBwgUjmPRdZddMkscrXNCwO/dmd+Hblnj4I9HQg5dwYxIWrbMdJx4xTk7/BbDGvFNlo=","trackType":""} Jul 02 20:49:19 nnn volumio[951]: verbose: CURRENT POSITION 0 Jul 02 20:49:19 nnn volumio[951]: info: CoreStateMachine::syncState stateService play Jul 02 20:49:19 nnn volumio[951]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:49:19 nnn volumio[951]: info: Received an update from plugin. extracting info from payload Jul 02 20:49:19 nnn volumio[951]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 02 20:49:19 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:19 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:19 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:19 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:19 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:19 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:19 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:19 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:19 nnn volumio[951]: info: ControllerMpd::pushState Jul 02 20:49:19 nnn volumio[951]: info: CoreCommandRouter::servicePushState Jul 02 20:49:19 nnn volumio[951]: verbose: In UPNP mode Jul 02 20:49:19 nnn volumio[951]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":376,"samplerate":"12 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Love Won't Sleep","artist":"Lostboycrow","album":"Love Won't Sleep","uri":"http://m8.music.126.net/20250702211417/c1ba97e5d8682afbb148caf59ca533b8/yyaac/obj/wonDkMOGw6XDiTHCmMOi/2061205332/7bce/2e61/5f6c/8edc3984316e08ca82dd998f129d580b.m4a?vuutv=XEK0TsWFBM2GMt+zIBJkdIpOKV93N2wB50R3RrevYBwgUjmPRdZddMkscrXNCwO/dmd+Hblnj4I9HQg5dwYxIWrbMdJx4xTk7/BbDGvFNlo=","trackType":""} Jul 02 20:49:19 nnn volumio[951]: verbose: CURRENT POSITION 0 Jul 02 20:49:19 nnn volumio[951]: info: CoreStateMachine::syncState stateService play Jul 02 20:49:19 nnn volumio[951]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:49:19 nnn volumio[951]: info: Received an update from plugin. extracting info from payload Jul 02 20:49:19 nnn volumio[951]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 02 20:49:19 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:19 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:19 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:19 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:19 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:19 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:19 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:19 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:19 nnn volumio[951]: info: ------------------------------ 298ms Jul 02 20:49:19 nnn volumio[951]: info: ------------------------------ 283ms Jul 02 20:49:19 nnn volumio[951]: info: ------------------------------ 279ms Jul 02 20:49:19 nnn volumio[951]: info: CoreCommandRouter::volumioGetQueue Jul 02 20:49:19 nnn volumio[951]: info: CoreStateMachine::getQueue Jul 02 20:49:19 nnn volumio[951]: info: CorePlayQueue::getQueue Jul 02 20:49:19 nnn volumio[951]: info: CoreCommandRouter::volumioGetQueue Jul 02 20:49:19 nnn volumio[951]: info: CoreStateMachine::getQueue Jul 02 20:49:19 nnn volumio[951]: info: CorePlayQueue::getQueue Jul 02 20:49:19 nnn volumio[951]: info: CoreCommandRouter::volumioGetQueue Jul 02 20:49:19 nnn volumio[951]: info: CoreStateMachine::getQueue Jul 02 20:49:19 nnn volumio[951]: info: CorePlayQueue::getQueue Jul 02 20:49:19 nnn volumio[951]: info: CoreCommandRouter::volumioGetQueue Jul 02 20:49:19 nnn volumio[951]: info: CoreStateMachine::getQueue Jul 02 20:49:19 nnn volumio[951]: info: CorePlayQueue::getQueue Jul 02 20:49:19 nnn volumio[951]: info: CoreCommandRouter::volumioGetQueue Jul 02 20:49:19 nnn volumio[951]: info: CoreStateMachine::getQueue Jul 02 20:49:19 nnn volumio[951]: info: CorePlayQueue::getQueue Jul 02 20:49:19 nnn volumio[951]: info: CoreCommandRouter::volumioGetQueue Jul 02 20:49:19 nnn volumio[951]: info: CoreStateMachine::getQueue Jul 02 20:49:19 nnn volumio[951]: info: CorePlayQueue::getQueue Jul 02 20:49:19 nnn volumio[951]: info: CoreCommandRouter::volumioGetQueue Jul 02 20:49:19 nnn volumio[951]: info: CoreStateMachine::getQueue Jul 02 20:49:19 nnn volumio[951]: info: CorePlayQueue::getQueue Jul 02 20:49:19 nnn volumio[951]: info: CoreCommandRouter::volumioGetQueue Jul 02 20:49:19 nnn volumio[951]: info: CoreStateMachine::getQueue Jul 02 20:49:19 nnn volumio[951]: info: CorePlayQueue::getQueue Jul 02 20:49:19 nnn volumio[951]: info: CoreCommandRouter::volumioGetQueue Jul 02 20:49:19 nnn volumio[951]: info: CoreStateMachine::getQueue Jul 02 20:49:19 nnn volumio[951]: info: CorePlayQueue::getQueue Jul 02 20:49:19 nnn volumio[951]: info: CoreCommandRouter::volumioGetQueue Jul 02 20:49:19 nnn volumio[951]: info: CoreStateMachine::getQueue Jul 02 20:49:19 nnn volumio[951]: info: CorePlayQueue::getQueue Jul 02 20:49:19 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:19 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:19 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:19 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:19 nnn volumio[951]: error: Serial API: Failed to decode command: 100, message: undefined Jul 02 20:49:19 nnn volumio[951]: error: Serial API: Failed to decode command: MAXVOL, message: Jul 02 20:49:19 nnn volumio[951]: error: Serial API: Failed to decode command: 100, message: undefined Jul 02 20:49:19 nnn volumio[951]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Jul 02 20:49:19 nnn volumio[951]: info: CoreCommandRouter::volumioGetQueue Jul 02 20:49:19 nnn volumio[951]: info: CoreStateMachine::getQueue Jul 02 20:49:19 nnn volumio[951]: info: CorePlayQueue::getQueue Jul 02 20:49:21 nnn volumio[951]: info: Jul 02 20:49:21 nnn volumio[951]: ---------------------------- MPD announces state update: player Jul 02 20:49:21 nnn volumio[951]: info: ControllerMpd::getState Jul 02 20:49:21 nnn volumio[951]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:49:21 nnn volumio[951]: info: Jul 02 20:49:21 nnn volumio[951]: ---------------------------- MPD announces state update: player Jul 02 20:49:21 nnn volumio[951]: info: ControllerMpd::getState Jul 02 20:49:21 nnn volumio[951]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:49:21 nnn volumio[951]: info: Jul 02 20:49:21 nnn volumio[951]: ---------------------------- MPD announces state update: player Jul 02 20:49:21 nnn volumio[951]: info: ControllerMpd::getState Jul 02 20:49:21 nnn volumio[951]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:49:21 nnn volumio[951]: info: sendMpdCommand status took 14 milliseconds Jul 02 20:49:21 nnn volumio[951]: info: sendMpdCommand status took 9 milliseconds Jul 02 20:49:21 nnn volumio[951]: info: sendMpdCommand status took 6 milliseconds Jul 02 20:49:21 nnn volumio[951]: verbose: ControllerMpd::parseState Jul 02 20:49:21 nnn volumio[951]: verbose: ControllerMpd::parseState Jul 02 20:49:21 nnn volumio[951]: verbose: ControllerMpd::parseState Jul 02 20:49:21 nnn volumio[951]: info: ControllerMpd::pushState Jul 02 20:49:21 nnn volumio[951]: info: CoreCommandRouter::servicePushState Jul 02 20:49:21 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:21 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:21 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:21 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:21 nnn volumio[951]: info: CorePlayQueue::getTrack 0 Jul 02 20:49:21 nnn volumio[951]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 02 20:49:21 nnn volumio[951]: verbose: CURRENT POSITION 0 Jul 02 20:49:21 nnn volumio[951]: info: CoreStateMachine::syncState stateService stop Jul 02 20:49:21 nnn volumio[951]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:49:21 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:21 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:21 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:21 nnn volumio[951]: info: CoreStateMachine::stPlaybackTimer Jul 02 20:49:21 nnn volumio[951]: info: ControllerMpd::pushState Jul 02 20:49:21 nnn volumio[951]: info: CoreCommandRouter::servicePushState Jul 02 20:49:21 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:21 nnn volumio[951]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 20:49:21 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:21 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:21 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:21 nnn volumio[951]: info: CorePlayQueue::getTrack 0 Jul 02 20:49:21 nnn volumio[951]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 02 20:49:21 nnn volumio[951]: verbose: CURRENT POSITION 0 Jul 02 20:49:21 nnn volumio[951]: info: CoreStateMachine::syncState stateService stop Jul 02 20:49:21 nnn volumio[951]: info: CoreStateMachine::syncState currentStatus stop Jul 02 20:49:21 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:21 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:21 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:21 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:21 nnn volumio[951]: info: No code Jul 02 20:49:21 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:21 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:21 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:21 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:21 nnn volumio[951]: info: ControllerMpd::pushState Jul 02 20:49:21 nnn volumio[951]: info: CoreCommandRouter::servicePushState Jul 02 20:49:21 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:21 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:21 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:21 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:21 nnn volumio[951]: info: CorePlayQueue::getTrack 0 Jul 02 20:49:21 nnn volumio[951]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 02 20:49:21 nnn volumio[951]: verbose: CURRENT POSITION 0 Jul 02 20:49:21 nnn volumio[951]: info: CoreStateMachine::syncState stateService stop Jul 02 20:49:21 nnn volumio[951]: info: CoreStateMachine::syncState currentStatus stop Jul 02 20:49:21 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:21 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:21 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:21 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:21 nnn volumio[951]: info: No code Jul 02 20:49:21 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:21 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:21 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:21 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:21 nnn volumio[951]: info: ------------------------------ 137ms Jul 02 20:49:21 nnn volumio[951]: info: ------------------------------ 134ms Jul 02 20:49:21 nnn volumio[951]: info: ------------------------------ 132ms Jul 02 20:49:21 nnn volumio[951]: info: CoreCommandRouter::volumioGetQueue Jul 02 20:49:21 nnn volumio[951]: info: CoreStateMachine::getQueue Jul 02 20:49:21 nnn volumio[951]: info: CorePlayQueue::getQueue Jul 02 20:49:22 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:22 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:22 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:22 nnn volumio[1229]: 0 Jul 02 20:49:22 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:22 nnn volumio[951]: error: Serial API: Failed to decode command: 100, message: undefined Jul 02 20:49:22 nnn volumio[951]: error: Serial API: Failed to decode command: MAXVOL, message: Jul 02 20:49:22 nnn volumio[951]: error: Serial API: Failed to decode command: 100, message: undefined Jul 02 20:49:22 nnn volumio[951]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Jul 02 20:49:22 nnn volumio[1229]: % Total % Received % Xferd Average Speed Time Time Time Current Jul 02 20:49:22 nnn volumio[1229]: Dload Upload Total Spent Left Speed Jul 02 20:49:22 nnn volumio[951]: info: Jul 02 20:49:22 nnn volumio[951]: ---------------------------- Client requests Volume 1 Jul 02 20:49:22 nnn volumio[951]: info: VolumeController::SetAlsaVolume1 Jul 02 20:49:22 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:22 nnn volumio[951]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 20:49:22 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:22 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:22 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:22 nnn volumio[1229]: [158B blob data] Jul 02 20:49:22 nnn volumio[1229]: {"time":1751460562159,"response":"volume Success"}1 Jul 02 20:49:22 nnn volumio[951]: info: Updating RAAT Signal Path Jul 02 20:49:28 nnn volumio[1229]: 5 Jul 02 20:49:28 nnn volumio[1229]: % Total % Received % Xferd Average Speed Time Time Time Current Jul 02 20:49:28 nnn volumio[1229]: Dload Upload Total Spent Left Speed Jul 02 20:49:28 nnn volumio[951]: info: Jul 02 20:49:28 nnn volumio[951]: ---------------------------- Client requests Volume 5 Jul 02 20:49:28 nnn volumio[951]: info: VolumeController::SetAlsaVolume5 Jul 02 20:49:28 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:28 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:28 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:28 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:28 nnn volumio[1229]: [158B blob data] Jul 02 20:49:28 nnn volumio[1229]: {"time":1751460568729,"response":"volume Success"}5 Jul 02 20:49:28 nnn volumio[951]: info: Updating RAAT Signal Path Jul 02 20:49:29 nnn volumio[1229]: 15 Jul 02 20:49:29 nnn volumio[1229]: % Total % Received % Xferd Average Speed Time Time Time Current Jul 02 20:49:29 nnn volumio[1229]: Dload Upload Total Spent Left Speed Jul 02 20:49:29 nnn volumio[951]: info: Jul 02 20:49:29 nnn volumio[951]: ---------------------------- Client requests Volume 15 Jul 02 20:49:29 nnn volumio[951]: info: VolumeController::SetAlsaVolume15 Jul 02 20:49:29 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:29 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:29 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:29 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:29 nnn volumio[1229]: [158B blob data] Jul 02 20:49:29 nnn volumio[1229]: {"time":1751460569719,"response":"volume Success"}15 Jul 02 20:49:29 nnn volumio[951]: info: Updating RAAT Signal Path Jul 02 20:49:35 nnn volumio[951]: info: Clearing queue after UPNP request Jul 02 20:49:36 nnn volumio[951]: info: CoreStateMachine::ClearQueue Jul 02 20:49:36 nnn volumio[951]: info: CoreStateMachine::stop Jul 02 20:49:36 nnn volumio[951]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 02 20:49:36 nnn volumio[951]: info: CorePlayQueue::clearPlayQueue Jul 02 20:49:36 nnn volumio[951]: info: CorePlayQueue::saveQueue Jul 02 20:49:36 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:36 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:36 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:36 nnn volumio[951]: info: CoreCommandRouter::volumioPushQueue Jul 02 20:49:36 nnn volumio[951]: info: Updating RAAT Signal Path Jul 02 20:49:36 nnn volumio[951]: info: Jul 02 20:49:36 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:36 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:36 nnn volumio[951]: info: Jul 02 20:49:36 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:36 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:36 nnn volumio[951]: info: Jul 02 20:49:36 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:36 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:36 nnn volumio[951]: error: updateQueue error: null Jul 02 20:49:36 nnn volumio[951]: error: updateQueue error: null Jul 02 20:49:36 nnn volumio[951]: error: updateQueue error: null Jul 02 20:49:36 nnn volumio[951]: info: ------------------------------ 15ms Jul 02 20:49:36 nnn volumio[951]: info: ------------------------------ 12ms Jul 02 20:49:36 nnn volumio[951]: info: ------------------------------ 10ms Jul 02 20:49:36 nnn volumio[951]: info: Starting UPNP Playback Jul 02 20:49:36 nnn volumio[951]: info: Preparing playback through UPNP Jul 02 20:49:36 nnn volumio[951]: info: CoreCommandRouter::volumioGetState Jul 02 20:49:36 nnn volumio[951]: info: CorePlayQueue::getTrack 0 Jul 02 20:49:36 nnn volumio[951]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 02 20:49:36 nnn volumio[951]: info: Jul 02 20:49:36 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:36 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:36 nnn volumio[951]: info: Jul 02 20:49:36 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:36 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:36 nnn volumio[951]: info: Jul 02 20:49:36 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:36 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:36 nnn volumio[951]: info: Jul 02 20:49:36 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:36 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:36 nnn volumio[951]: info: ------------------------------ 12ms Jul 02 20:49:36 nnn volumio[951]: info: ------------------------------ 10ms Jul 02 20:49:36 nnn volumio[951]: info: ------------------------------ 7ms Jul 02 20:49:36 nnn volumio[951]: info: ------------------------------ 6ms Jul 02 20:49:36 nnn volumio[951]: info: Jul 02 20:49:36 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:36 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:36 nnn volumio[951]: info: Jul 02 20:49:36 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:36 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:36 nnn volumio[951]: info: Jul 02 20:49:36 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:36 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:36 nnn volumio[951]: info: ------------------------------ 9ms Jul 02 20:49:36 nnn volumio[951]: info: ------------------------------ 7ms Jul 02 20:49:36 nnn volumio[951]: info: ------------------------------ 5ms Jul 02 20:49:36 nnn volumio[951]: info: Jul 02 20:49:36 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:36 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:36 nnn volumio[951]: info: Jul 02 20:49:36 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:36 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:36 nnn volumio[951]: info: Jul 02 20:49:36 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:36 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:36 nnn volumio[951]: info: ------------------------------ 15ms Jul 02 20:49:36 nnn volumio[951]: info: ------------------------------ 12ms Jul 02 20:49:36 nnn volumio[951]: info: ------------------------------ 5ms Jul 02 20:49:36 nnn volumio[951]: info: Jul 02 20:49:36 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:36 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:36 nnn volumio[951]: info: Jul 02 20:49:36 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:36 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:36 nnn volumio[951]: info: Jul 02 20:49:36 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:36 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:36 nnn volumio[951]: info: ------------------------------ 9ms Jul 02 20:49:36 nnn volumio[951]: info: ------------------------------ 7ms Jul 02 20:49:36 nnn volumio[951]: info: ------------------------------ 6ms Jul 02 20:49:36 nnn volumio[951]: info: Jul 02 20:49:36 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:36 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:36 nnn volumio[951]: info: Jul 02 20:49:36 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:36 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:36 nnn volumio[951]: info: Jul 02 20:49:36 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:36 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:36 nnn volumio[951]: info: ------------------------------ 10ms Jul 02 20:49:36 nnn volumio[951]: info: ------------------------------ 7ms Jul 02 20:49:36 nnn volumio[951]: info: ------------------------------ 6ms Jul 02 20:49:36 nnn volumio[951]: info: Jul 02 20:49:36 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:36 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:36 nnn volumio[951]: info: Jul 02 20:49:36 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:36 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:36 nnn volumio[951]: info: ------------------------------ 8ms Jul 02 20:49:36 nnn volumio[951]: info: ------------------------------ 6ms Jul 02 20:49:37 nnn volumio[951]: info: Jul 02 20:49:37 nnn volumio[951]: ---------------------------- MPD announces state update: player Jul 02 20:49:37 nnn volumio[951]: info: ControllerMpd::getState Jul 02 20:49:37 nnn volumio[951]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:49:37 nnn volumio[951]: info: Jul 02 20:49:37 nnn volumio[951]: ---------------------------- MPD announces state update: player Jul 02 20:49:37 nnn volumio[951]: info: ControllerMpd::getState Jul 02 20:49:37 nnn volumio[951]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:49:37 nnn volumio[951]: info: Jul 02 20:49:37 nnn volumio[951]: ---------------------------- MPD announces state update: player Jul 02 20:49:37 nnn volumio[951]: info: ControllerMpd::getState Jul 02 20:49:37 nnn volumio[951]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:49:37 nnn volumio[951]: info: Jul 02 20:49:37 nnn volumio[951]: ---------------------------- MPD announces state update: player Jul 02 20:49:37 nnn volumio[951]: info: ControllerMpd::getState Jul 02 20:49:37 nnn volumio[951]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:49:37 nnn volumio[951]: info: sendMpdCommand status took 23 milliseconds Jul 02 20:49:37 nnn volumio[951]: info: sendMpdCommand status took 16 milliseconds Jul 02 20:49:37 nnn volumio[951]: info: sendMpdCommand status took 10 milliseconds Jul 02 20:49:37 nnn volumio[951]: verbose: ControllerMpd::parseState Jul 02 20:49:37 nnn volumio[951]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:49:37 nnn volumio[951]: verbose: ControllerMpd::parseState Jul 02 20:49:37 nnn volumio[951]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:49:37 nnn volumio[951]: verbose: ControllerMpd::parseState Jul 02 20:49:37 nnn volumio[951]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:49:37 nnn volumio[951]: info: Jul 02 20:49:37 nnn volumio[951]: ---------------------------- MPD announces state update: player Jul 02 20:49:37 nnn volumio[951]: info: ControllerMpd::getState Jul 02 20:49:37 nnn volumio[951]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:49:37 nnn volumio[951]: info: Jul 02 20:49:37 nnn volumio[951]: ---------------------------- MPD announces state update: player Jul 02 20:49:37 nnn volumio[951]: info: ControllerMpd::getState Jul 02 20:49:37 nnn volumio[951]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:49:37 nnn volumio[951]: info: sendMpdCommand status took 34 milliseconds Jul 02 20:49:37 nnn volumio[951]: info: sendMpdCommand playlistinfo took 28 milliseconds Jul 02 20:49:37 nnn volumio[951]: info: sendMpdCommand playlistinfo took 27 milliseconds Jul 02 20:49:37 nnn volumio[951]: info: sendMpdCommand playlistinfo took 25 milliseconds Jul 02 20:49:37 nnn volumio[951]: verbose: ControllerMpd::parseState Jul 02 20:49:37 nnn volumio[951]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:49:37 nnn volumio[951]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:49:37 nnn volumio[951]: info: ControllerMpd::pushState Jul 02 20:49:37 nnn volumio[951]: info: CoreCommandRouter::servicePushState Jul 02 20:49:37 nnn volumio[951]: verbose: In UPNP mode Jul 02 20:49:37 nnn volumio[951]: verbose: STATE SERVICE {"status":"play","position":0,"seek":483,"duration":233,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"astral","artist":"明早起来","album":"pops","uri":"http://m701.music.126.net/20250702211435/198d01ee18b6d6611cbe0a8a3c9a7c1e/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/29131986733/eff4/7104/d4da/18f604943e71ee8b6809b5454878e48f.m4a?vuutv=xlA3jfjZWXGLE8pygh8bb1gtx3xbowH8OzUzRpfJYFEEspNFfIAq64U2GbU8g/XKrnCJMz1QcMUVP07ri6LC0K4RQhI/JzaT9SElPKuaGdA=","trackType":""} Jul 02 20:49:37 nnn volumio[951]: verbose: CURRENT POSITION 0 Jul 02 20:49:37 nnn volumio[951]: info: CoreStateMachine::syncState stateService play Jul 02 20:49:37 nnn volumio[951]: info: CoreStateMachine::syncState currentStatus stop Jul 02 20:49:37 nnn volumio[951]: info: ControllerMpd::pushState Jul 02 20:49:37 nnn volumio[951]: info: CoreCommandRouter::servicePushState Jul 02 20:49:37 nnn volumio[951]: verbose: In UPNP mode Jul 02 20:49:37 nnn volumio[951]: verbose: STATE SERVICE {"status":"play","position":0,"seek":483,"duration":233,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"astral","artist":"明早起来","album":"pops","uri":"http://m701.music.126.net/20250702211435/198d01ee18b6d6611cbe0a8a3c9a7c1e/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/29131986733/eff4/7104/d4da/18f604943e71ee8b6809b5454878e48f.m4a?vuutv=xlA3jfjZWXGLE8pygh8bb1gtx3xbowH8OzUzRpfJYFEEspNFfIAq64U2GbU8g/XKrnCJMz1QcMUVP07ri6LC0K4RQhI/JzaT9SElPKuaGdA=","trackType":""} Jul 02 20:49:37 nnn volumio[951]: verbose: CURRENT POSITION 0 Jul 02 20:49:37 nnn volumio[951]: info: CoreStateMachine::syncState stateService play Jul 02 20:49:37 nnn volumio[951]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:49:37 nnn volumio[951]: info: Received an update from plugin. extracting info from payload Jul 02 20:49:37 nnn volumio[951]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 02 20:49:37 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:37 nnn volumio[951]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 20:49:37 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:37 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:37 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:37 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:37 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:37 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:37 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:37 nnn volumio[951]: info: ControllerMpd::pushState Jul 02 20:49:37 nnn volumio[951]: info: CoreCommandRouter::servicePushState Jul 02 20:49:37 nnn volumio[951]: verbose: In UPNP mode Jul 02 20:49:37 nnn volumio[951]: verbose: STATE SERVICE {"status":"play","position":0,"seek":609,"duration":233,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"astral","artist":"明早起来","album":"pops","uri":"http://m701.music.126.net/20250702211435/198d01ee18b6d6611cbe0a8a3c9a7c1e/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/29131986733/eff4/7104/d4da/18f604943e71ee8b6809b5454878e48f.m4a?vuutv=xlA3jfjZWXGLE8pygh8bb1gtx3xbowH8OzUzRpfJYFEEspNFfIAq64U2GbU8g/XKrnCJMz1QcMUVP07ri6LC0K4RQhI/JzaT9SElPKuaGdA=","trackType":""} Jul 02 20:49:37 nnn volumio[951]: verbose: CURRENT POSITION 0 Jul 02 20:49:37 nnn volumio[951]: info: CoreStateMachine::syncState stateService play Jul 02 20:49:37 nnn volumio[951]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:49:37 nnn volumio[951]: info: Received an update from plugin. extracting info from payload Jul 02 20:49:37 nnn volumio[951]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 02 20:49:37 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:37 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:37 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:37 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:37 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:37 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:37 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:37 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:37 nnn volumio[951]: info: ------------------------------ 162ms Jul 02 20:49:37 nnn volumio[951]: info: ------------------------------ 184ms Jul 02 20:49:37 nnn volumio[951]: info: ------------------------------ 179ms Jul 02 20:49:37 nnn volumio[951]: info: sendMpdCommand status took 148 milliseconds Jul 02 20:49:37 nnn volumio[951]: info: sendMpdCommand status took 142 milliseconds Jul 02 20:49:37 nnn volumio[951]: info: sendMpdCommand playlistinfo took 131 milliseconds Jul 02 20:49:37 nnn volumio[951]: verbose: ControllerMpd::parseState Jul 02 20:49:37 nnn volumio[951]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:49:37 nnn volumio[951]: verbose: ControllerMpd::parseState Jul 02 20:49:37 nnn volumio[951]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:49:37 nnn volumio[951]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:49:37 nnn volumio[951]: info: ControllerMpd::pushState Jul 02 20:49:37 nnn volumio[951]: info: CoreCommandRouter::servicePushState Jul 02 20:49:37 nnn volumio[951]: verbose: In UPNP mode Jul 02 20:49:37 nnn volumio[951]: verbose: STATE SERVICE {"status":"play","position":0,"seek":609,"duration":233,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"astral","artist":"明早起来","album":"pops","uri":"http://m701.music.126.net/20250702211435/198d01ee18b6d6611cbe0a8a3c9a7c1e/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/29131986733/eff4/7104/d4da/18f604943e71ee8b6809b5454878e48f.m4a?vuutv=xlA3jfjZWXGLE8pygh8bb1gtx3xbowH8OzUzRpfJYFEEspNFfIAq64U2GbU8g/XKrnCJMz1QcMUVP07ri6LC0K4RQhI/JzaT9SElPKuaGdA=","trackType":""} Jul 02 20:49:37 nnn volumio[951]: verbose: CURRENT POSITION 0 Jul 02 20:49:37 nnn volumio[951]: info: CoreStateMachine::syncState stateService play Jul 02 20:49:37 nnn volumio[951]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:49:37 nnn volumio[951]: info: Received an update from plugin. extracting info from payload Jul 02 20:49:37 nnn volumio[951]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 02 20:49:37 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:37 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:37 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:37 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:37 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:37 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:37 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:37 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:37 nnn volumio[951]: info: ------------------------------ 232ms Jul 02 20:49:37 nnn volumio[951]: info: CoreCommandRouter::volumioGetQueue Jul 02 20:49:37 nnn volumio[951]: info: CoreStateMachine::getQueue Jul 02 20:49:37 nnn volumio[951]: info: CorePlayQueue::getQueue Jul 02 20:49:37 nnn volumio[951]: info: CoreCommandRouter::volumioGetQueue Jul 02 20:49:37 nnn volumio[951]: info: CoreStateMachine::getQueue Jul 02 20:49:37 nnn volumio[951]: info: CorePlayQueue::getQueue Jul 02 20:49:37 nnn volumio[951]: info: CoreCommandRouter::volumioGetQueue Jul 02 20:49:37 nnn volumio[951]: info: CoreStateMachine::getQueue Jul 02 20:49:37 nnn volumio[951]: info: CorePlayQueue::getQueue Jul 02 20:49:37 nnn volumio[951]: info: CoreCommandRouter::volumioGetQueue Jul 02 20:49:37 nnn volumio[951]: info: CoreStateMachine::getQueue Jul 02 20:49:37 nnn volumio[951]: info: CorePlayQueue::getQueue Jul 02 20:49:37 nnn volumio[951]: info: CoreCommandRouter::volumioGetQueue Jul 02 20:49:37 nnn volumio[951]: info: CoreStateMachine::getQueue Jul 02 20:49:37 nnn volumio[951]: info: CorePlayQueue::getQueue Jul 02 20:49:37 nnn volumio[951]: info: CoreCommandRouter::volumioGetQueue Jul 02 20:49:37 nnn volumio[951]: info: CoreStateMachine::getQueue Jul 02 20:49:37 nnn volumio[951]: info: CorePlayQueue::getQueue Jul 02 20:49:37 nnn volumio[951]: info: Updating RAAT Signal Path Jul 02 20:49:37 nnn volumio[951]: info: Updating RAAT Signal Path Jul 02 20:49:37 nnn volumio[951]: info: Updating RAAT Signal Path Jul 02 20:49:37 nnn volumio[951]: info: Updating RAAT Signal Path Jul 02 20:49:37 nnn volumio[951]: info: Updating RAAT Signal Path Jul 02 20:49:37 nnn volumio[951]: info: Updating RAAT Signal Path Jul 02 20:49:37 nnn volumio[951]: info: sendMpdCommand playlistinfo took 142 milliseconds Jul 02 20:49:37 nnn volumio[951]: info: sendMpdCommand playlistinfo took 141 milliseconds Jul 02 20:49:37 nnn volumio[951]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:49:37 nnn volumio[951]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:49:37 nnn volumio[951]: info: ControllerMpd::pushState Jul 02 20:49:37 nnn volumio[951]: info: CoreCommandRouter::servicePushState Jul 02 20:49:37 nnn volumio[951]: verbose: In UPNP mode Jul 02 20:49:37 nnn volumio[951]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1238,"duration":233,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"astral","artist":"明早起来","album":"pops","uri":"http://m701.music.126.net/20250702211435/198d01ee18b6d6611cbe0a8a3c9a7c1e/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/29131986733/eff4/7104/d4da/18f604943e71ee8b6809b5454878e48f.m4a?vuutv=xlA3jfjZWXGLE8pygh8bb1gtx3xbowH8OzUzRpfJYFEEspNFfIAq64U2GbU8g/XKrnCJMz1QcMUVP07ri6LC0K4RQhI/JzaT9SElPKuaGdA=","trackType":""} Jul 02 20:49:37 nnn volumio[951]: verbose: CURRENT POSITION 0 Jul 02 20:49:37 nnn volumio[951]: info: CoreStateMachine::syncState stateService play Jul 02 20:49:37 nnn volumio[951]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:49:37 nnn volumio[951]: info: Received an update from plugin. extracting info from payload Jul 02 20:49:37 nnn volumio[951]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 02 20:49:37 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:37 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:37 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:37 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:37 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:37 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:37 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:37 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:37 nnn volumio[951]: info: ControllerMpd::pushState Jul 02 20:49:37 nnn volumio[951]: info: CoreCommandRouter::servicePushState Jul 02 20:49:37 nnn volumio[951]: verbose: In UPNP mode Jul 02 20:49:37 nnn volumio[951]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1238,"duration":233,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"astral","artist":"明早起来","album":"pops","uri":"http://m701.music.126.net/20250702211435/198d01ee18b6d6611cbe0a8a3c9a7c1e/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/29131986733/eff4/7104/d4da/18f604943e71ee8b6809b5454878e48f.m4a?vuutv=xlA3jfjZWXGLE8pygh8bb1gtx3xbowH8OzUzRpfJYFEEspNFfIAq64U2GbU8g/XKrnCJMz1QcMUVP07ri6LC0K4RQhI/JzaT9SElPKuaGdA=","trackType":""} Jul 02 20:49:37 nnn volumio[951]: verbose: CURRENT POSITION 0 Jul 02 20:49:37 nnn volumio[951]: info: CoreStateMachine::syncState stateService play Jul 02 20:49:37 nnn volumio[951]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:49:37 nnn volumio[951]: info: Received an update from plugin. extracting info from payload Jul 02 20:49:37 nnn volumio[951]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 02 20:49:37 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:37 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:37 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:37 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:37 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:37 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:37 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:37 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:37 nnn volumio[951]: info: ------------------------------ 382ms Jul 02 20:49:37 nnn volumio[951]: info: ------------------------------ 379ms Jul 02 20:49:37 nnn volumio[951]: info: CoreCommandRouter::volumioGetQueue Jul 02 20:49:37 nnn volumio[951]: info: CoreStateMachine::getQueue Jul 02 20:49:37 nnn volumio[951]: info: CorePlayQueue::getQueue Jul 02 20:49:37 nnn volumio[951]: info: CoreCommandRouter::volumioGetQueue Jul 02 20:49:37 nnn volumio[951]: info: CoreStateMachine::getQueue Jul 02 20:49:37 nnn volumio[951]: info: CorePlayQueue::getQueue Jul 02 20:49:37 nnn volumio[951]: info: CoreCommandRouter::volumioGetQueue Jul 02 20:49:37 nnn volumio[951]: info: CoreStateMachine::getQueue Jul 02 20:49:37 nnn volumio[951]: info: CorePlayQueue::getQueue Jul 02 20:49:37 nnn volumio[951]: info: CoreCommandRouter::volumioGetQueue Jul 02 20:49:37 nnn volumio[951]: info: CoreStateMachine::getQueue Jul 02 20:49:37 nnn volumio[951]: info: CorePlayQueue::getQueue Jul 02 20:49:37 nnn volumio[951]: info: Updating RAAT Signal Path Jul 02 20:49:37 nnn volumio[951]: info: Updating RAAT Signal Path Jul 02 20:49:37 nnn volumio[951]: info: Updating RAAT Signal Path Jul 02 20:49:37 nnn volumio[951]: info: Updating RAAT Signal Path Jul 02 20:49:37 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:37 nnn volumio[951]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 20:49:37 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:37 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:37 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:37 nnn volumio[951]: error: Serial API: Failed to decode command: 100, message: undefined Jul 02 20:49:37 nnn volumio[951]: error: Serial API: Failed to decode command: MAXVOL, message: Jul 02 20:49:37 nnn volumio[951]: error: Serial API: Failed to decode command: 100, message: undefined Jul 02 20:49:37 nnn volumio[951]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Jul 02 20:49:37 nnn volumio[951]: info: CoreCommandRouter::volumioGetQueue Jul 02 20:49:37 nnn volumio[951]: info: CoreStateMachine::getQueue Jul 02 20:49:37 nnn volumio[951]: info: CorePlayQueue::getQueue Jul 02 20:49:49 nnn ntpd[939]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jul 02 20:49:52 nnn volumio[951]: info: Jul 02 20:49:52 nnn volumio[951]: ---------------------------- MPD announces state update: player Jul 02 20:49:52 nnn volumio[951]: info: ControllerMpd::getState Jul 02 20:49:52 nnn volumio[951]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:49:52 nnn volumio[951]: info: Jul 02 20:49:52 nnn volumio[951]: ---------------------------- MPD announces state update: player Jul 02 20:49:52 nnn volumio[951]: info: ControllerMpd::getState Jul 02 20:49:52 nnn volumio[951]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:49:52 nnn volumio[951]: info: Jul 02 20:49:52 nnn volumio[951]: ---------------------------- MPD announces state update: player Jul 02 20:49:52 nnn volumio[951]: info: ControllerMpd::getState Jul 02 20:49:52 nnn volumio[951]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:49:52 nnn volumio[951]: info: Jul 02 20:49:52 nnn volumio[951]: ---------------------------- MPD announces state update: player Jul 02 20:49:52 nnn volumio[951]: info: ControllerMpd::getState Jul 02 20:49:52 nnn volumio[951]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:49:52 nnn volumio[951]: info: sendMpdCommand status took 13 milliseconds Jul 02 20:49:52 nnn volumio[951]: info: sendMpdCommand status took 10 milliseconds Jul 02 20:49:52 nnn volumio[951]: info: sendMpdCommand status took 7 milliseconds Jul 02 20:49:52 nnn volumio[951]: verbose: ControllerMpd::parseState Jul 02 20:49:52 nnn volumio[951]: verbose: ControllerMpd::parseState Jul 02 20:49:52 nnn volumio[951]: verbose: ControllerMpd::parseState Jul 02 20:49:52 nnn volumio[951]: info: ControllerMpd::pushState Jul 02 20:49:52 nnn volumio[951]: info: CoreCommandRouter::servicePushState Jul 02 20:49:52 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:52 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:52 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:52 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:52 nnn volumio[951]: info: CorePlayQueue::getTrack 0 Jul 02 20:49:52 nnn volumio[951]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 02 20:49:52 nnn volumio[951]: verbose: CURRENT POSITION 0 Jul 02 20:49:52 nnn volumio[951]: info: CoreStateMachine::syncState stateService stop Jul 02 20:49:52 nnn volumio[951]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:49:52 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:52 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:52 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:52 nnn volumio[951]: info: CoreStateMachine::stPlaybackTimer Jul 02 20:49:52 nnn volumio[951]: info: ControllerMpd::pushState Jul 02 20:49:52 nnn volumio[951]: info: CoreCommandRouter::servicePushState Jul 02 20:49:52 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:52 nnn volumio[951]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 20:49:52 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:52 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:52 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:52 nnn volumio[951]: info: CorePlayQueue::getTrack 0 Jul 02 20:49:52 nnn volumio[951]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 02 20:49:52 nnn volumio[951]: verbose: CURRENT POSITION 0 Jul 02 20:49:52 nnn volumio[951]: info: CoreStateMachine::syncState stateService stop Jul 02 20:49:52 nnn volumio[951]: info: CoreStateMachine::syncState currentStatus stop Jul 02 20:49:52 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:52 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:52 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:52 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:52 nnn volumio[951]: info: No code Jul 02 20:49:52 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:52 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:52 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:52 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:52 nnn volumio[951]: info: ControllerMpd::pushState Jul 02 20:49:52 nnn volumio[951]: info: CoreCommandRouter::servicePushState Jul 02 20:49:52 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:52 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:52 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:52 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:52 nnn volumio[951]: info: CorePlayQueue::getTrack 0 Jul 02 20:49:52 nnn volumio[951]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 02 20:49:52 nnn volumio[951]: verbose: CURRENT POSITION 0 Jul 02 20:49:52 nnn volumio[951]: info: CoreStateMachine::syncState stateService stop Jul 02 20:49:52 nnn volumio[951]: info: CoreStateMachine::syncState currentStatus stop Jul 02 20:49:52 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:52 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:52 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:52 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:52 nnn volumio[951]: info: No code Jul 02 20:49:52 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:52 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:52 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:52 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:52 nnn volumio[951]: info: ------------------------------ 135ms Jul 02 20:49:52 nnn volumio[951]: info: ------------------------------ 129ms Jul 02 20:49:52 nnn volumio[951]: info: ------------------------------ 128ms Jul 02 20:49:52 nnn volumio[951]: info: Jul 02 20:49:52 nnn volumio[951]: ---------------------------- MPD announces state update: player Jul 02 20:49:52 nnn volumio[951]: info: ControllerMpd::getState Jul 02 20:49:52 nnn volumio[951]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:49:52 nnn volumio[951]: info: Jul 02 20:49:52 nnn volumio[951]: ---------------------------- MPD announces state update: player Jul 02 20:49:52 nnn volumio[951]: info: ControllerMpd::getState Jul 02 20:49:52 nnn volumio[951]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:49:52 nnn volumio[951]: info: Clearing queue after UPNP request Jul 02 20:49:52 nnn volumio[951]: info: sendMpdCommand status took 133 milliseconds Jul 02 20:49:52 nnn volumio[951]: info: sendMpdCommand status took 9 milliseconds Jul 02 20:49:52 nnn volumio[951]: info: sendMpdCommand status took 6 milliseconds Jul 02 20:49:52 nnn volumio[951]: verbose: ControllerMpd::parseState Jul 02 20:49:52 nnn volumio[951]: verbose: ControllerMpd::parseState Jul 02 20:49:52 nnn volumio[951]: verbose: ControllerMpd::parseState Jul 02 20:49:52 nnn volumio[951]: info: ControllerMpd::pushState Jul 02 20:49:52 nnn volumio[951]: info: CoreCommandRouter::servicePushState Jul 02 20:49:52 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:52 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:52 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:52 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:52 nnn volumio[951]: info: CorePlayQueue::getTrack 0 Jul 02 20:49:52 nnn volumio[951]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 02 20:49:52 nnn volumio[951]: verbose: CURRENT POSITION 0 Jul 02 20:49:52 nnn volumio[951]: info: CoreStateMachine::syncState stateService stop Jul 02 20:49:52 nnn volumio[951]: info: CoreStateMachine::syncState currentStatus stop Jul 02 20:49:52 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:52 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:52 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:52 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:52 nnn volumio[951]: info: No code Jul 02 20:49:52 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:52 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:52 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:52 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:52 nnn volumio[951]: info: ControllerMpd::pushState Jul 02 20:49:52 nnn volumio[951]: info: CoreCommandRouter::servicePushState Jul 02 20:49:52 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:52 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:52 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:52 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:52 nnn volumio[951]: info: CorePlayQueue::getTrack 0 Jul 02 20:49:52 nnn volumio[951]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 02 20:49:52 nnn volumio[951]: verbose: CURRENT POSITION 0 Jul 02 20:49:52 nnn volumio[951]: info: CoreStateMachine::syncState stateService stop Jul 02 20:49:52 nnn volumio[951]: info: CoreStateMachine::syncState currentStatus stop Jul 02 20:49:52 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:52 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:52 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:52 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:52 nnn volumio[951]: info: No code Jul 02 20:49:52 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:52 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:52 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:52 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:52 nnn volumio[951]: info: ControllerMpd::pushState Jul 02 20:49:52 nnn volumio[951]: info: CoreCommandRouter::servicePushState Jul 02 20:49:52 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:52 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:52 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:52 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:52 nnn volumio[951]: info: CorePlayQueue::getTrack 0 Jul 02 20:49:52 nnn volumio[951]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 02 20:49:52 nnn volumio[951]: verbose: CURRENT POSITION 0 Jul 02 20:49:52 nnn volumio[951]: info: CoreStateMachine::syncState stateService stop Jul 02 20:49:52 nnn volumio[951]: info: CoreStateMachine::syncState currentStatus stop Jul 02 20:49:52 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:52 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:52 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:52 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:52 nnn volumio[951]: info: No code Jul 02 20:49:52 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:52 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:52 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:52 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:52 nnn volumio[951]: info: ------------------------------ 262ms Jul 02 20:49:52 nnn volumio[951]: info: ------------------------------ 139ms Jul 02 20:49:52 nnn volumio[951]: info: ------------------------------ 138ms Jul 02 20:49:52 nnn volumio[951]: info: CoreCommandRouter::volumioGetQueue Jul 02 20:49:52 nnn volumio[951]: info: CoreStateMachine::getQueue Jul 02 20:49:52 nnn volumio[951]: info: CorePlayQueue::getQueue Jul 02 20:49:52 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:52 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:52 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:52 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:52 nnn volumio[951]: error: Serial API: Failed to decode command: 100, message: undefined Jul 02 20:49:52 nnn volumio[951]: error: Serial API: Failed to decode command: MAXVOL, message: Jul 02 20:49:52 nnn volumio[951]: error: Serial API: Failed to decode command: 100, message: undefined Jul 02 20:49:52 nnn volumio[951]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Jul 02 20:49:52 nnn volumio[951]: info: CoreStateMachine::ClearQueue Jul 02 20:49:52 nnn volumio[951]: info: CoreStateMachine::stop Jul 02 20:49:52 nnn volumio[951]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 02 20:49:52 nnn volumio[951]: info: CorePlayQueue::clearPlayQueue Jul 02 20:49:52 nnn volumio[951]: info: CorePlayQueue::saveQueue Jul 02 20:49:52 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:52 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:52 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:52 nnn volumio[951]: info: CoreCommandRouter::volumioPushQueue Jul 02 20:49:53 nnn volumio[951]: info: Jul 02 20:49:53 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:53 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:53 nnn volumio[951]: info: Jul 02 20:49:53 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:53 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:53 nnn volumio[951]: info: Jul 02 20:49:53 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:53 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:53 nnn volumio[951]: error: updateQueue error: null Jul 02 20:49:53 nnn volumio[951]: error: updateQueue error: null Jul 02 20:49:53 nnn volumio[951]: error: updateQueue error: null Jul 02 20:49:53 nnn volumio[951]: info: ------------------------------ 15ms Jul 02 20:49:53 nnn volumio[951]: info: ------------------------------ 12ms Jul 02 20:49:53 nnn volumio[951]: info: ------------------------------ 8ms Jul 02 20:49:53 nnn volumio[951]: info: Starting UPNP Playback Jul 02 20:49:53 nnn volumio[951]: info: Preparing playback through UPNP Jul 02 20:49:53 nnn volumio[951]: info: CoreCommandRouter::volumioGetState Jul 02 20:49:53 nnn volumio[951]: info: CorePlayQueue::getTrack 0 Jul 02 20:49:53 nnn volumio[951]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 02 20:49:53 nnn volumio[951]: info: Jul 02 20:49:53 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:53 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:53 nnn volumio[951]: info: Jul 02 20:49:53 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:53 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:53 nnn volumio[951]: info: Jul 02 20:49:53 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:53 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:53 nnn volumio[951]: info: Jul 02 20:49:53 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:53 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:53 nnn volumio[951]: info: ------------------------------ 13ms Jul 02 20:49:53 nnn volumio[951]: info: ------------------------------ 10ms Jul 02 20:49:53 nnn volumio[951]: info: ------------------------------ 8ms Jul 02 20:49:53 nnn volumio[951]: info: ------------------------------ 6ms Jul 02 20:49:53 nnn volumio[951]: info: Jul 02 20:49:53 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:53 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:53 nnn volumio[951]: info: Jul 02 20:49:53 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:53 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:53 nnn volumio[951]: info: Jul 02 20:49:53 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:53 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:53 nnn volumio[951]: info: ------------------------------ 9ms Jul 02 20:49:53 nnn volumio[951]: info: ------------------------------ 6ms Jul 02 20:49:53 nnn volumio[951]: info: Jul 02 20:49:53 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:53 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:53 nnn volumio[951]: info: Jul 02 20:49:53 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:53 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:53 nnn volumio[951]: info: ------------------------------ 12ms Jul 02 20:49:53 nnn volumio[951]: info: ------------------------------ 9ms Jul 02 20:49:53 nnn volumio[951]: info: ------------------------------ 6ms Jul 02 20:49:53 nnn volumio[951]: info: Jul 02 20:49:53 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:53 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:53 nnn volumio[951]: info: Jul 02 20:49:53 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:53 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:53 nnn volumio[951]: info: ------------------------------ 7ms Jul 02 20:49:53 nnn volumio[951]: info: ------------------------------ 4ms Jul 02 20:49:53 nnn volumio[951]: info: Jul 02 20:49:53 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:53 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:53 nnn volumio[951]: info: Jul 02 20:49:53 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:53 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:53 nnn volumio[951]: info: ------------------------------ 6ms Jul 02 20:49:53 nnn volumio[951]: info: ------------------------------ 5ms Jul 02 20:49:53 nnn volumio[951]: info: Jul 02 20:49:53 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:53 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:53 nnn volumio[951]: info: Jul 02 20:49:53 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:53 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:53 nnn volumio[951]: info: Jul 02 20:49:53 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:53 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:53 nnn volumio[951]: info: ------------------------------ 9ms Jul 02 20:49:53 nnn volumio[951]: info: ------------------------------ 7ms Jul 02 20:49:53 nnn volumio[951]: info: Jul 02 20:49:53 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:53 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:53 nnn volumio[951]: info: Jul 02 20:49:53 nnn volumio[951]: ---------------------------- MPD announces system playlist update Jul 02 20:49:53 nnn volumio[951]: info: Ignoring MPD Status Update Jul 02 20:49:53 nnn volumio[951]: info: ------------------------------ 11ms Jul 02 20:49:53 nnn volumio[951]: info: ------------------------------ 8ms Jul 02 20:49:53 nnn volumio[951]: info: ------------------------------ 5ms Jul 02 20:49:53 nnn volumio[951]: info: Jul 02 20:49:53 nnn volumio[951]: ---------------------------- MPD announces state update: player Jul 02 20:49:53 nnn volumio[951]: info: ControllerMpd::getState Jul 02 20:49:53 nnn volumio[951]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:49:53 nnn volumio[951]: info: Jul 02 20:49:53 nnn volumio[951]: ---------------------------- MPD announces state update: player Jul 02 20:49:53 nnn volumio[951]: info: ControllerMpd::getState Jul 02 20:49:53 nnn volumio[951]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:49:53 nnn volumio[951]: info: sendMpdCommand status took 7 milliseconds Jul 02 20:49:53 nnn volumio[951]: verbose: ControllerMpd::parseState Jul 02 20:49:53 nnn volumio[951]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:49:53 nnn volumio[951]: info: Jul 02 20:49:53 nnn volumio[951]: ---------------------------- MPD announces state update: player Jul 02 20:49:53 nnn volumio[951]: info: ControllerMpd::getState Jul 02 20:49:53 nnn volumio[951]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:49:53 nnn volumio[951]: info: Jul 02 20:49:53 nnn volumio[951]: ---------------------------- MPD announces state update: player Jul 02 20:49:53 nnn volumio[951]: info: ControllerMpd::getState Jul 02 20:49:53 nnn volumio[951]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:49:53 nnn volumio[951]: info: sendMpdCommand status took 25 milliseconds Jul 02 20:49:53 nnn volumio[951]: info: sendMpdCommand playlistinfo took 20 milliseconds Jul 02 20:49:53 nnn volumio[951]: info: sendMpdCommand status took 14 milliseconds Jul 02 20:49:53 nnn volumio[951]: verbose: ControllerMpd::parseState Jul 02 20:49:53 nnn volumio[951]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:49:53 nnn volumio[951]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:49:53 nnn volumio[951]: verbose: ControllerMpd::parseState Jul 02 20:49:53 nnn volumio[951]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:49:53 nnn volumio[951]: info: ControllerMpd::pushState Jul 02 20:49:53 nnn volumio[951]: info: CoreCommandRouter::servicePushState Jul 02 20:49:53 nnn volumio[951]: verbose: In UPNP mode Jul 02 20:49:53 nnn volumio[951]: verbose: STATE SERVICE {"status":"play","position":0,"seek":480,"duration":219,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"The Killing Moon","artist":"Nouvelle Vague","album":"Bande à Part","uri":"http://m801.music.126.net/20250702211452/d1d4cfaf0a382ef59854178e1f02847d/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/29083156639/d122/98df/bc44/bd8a27e6a13b8718dbd63a8cb3a2798c.m4a?vuutv=GSd4tmQRZaK7A8Y+FhCyhp6av5lXgiR01TY8oYrPn3HqUmuXnZgvoL4jcJclrJfHFgaJQkDG06+Q8DuayCyQWFWs4lJDMXDEHarToG2BuR8=","trackType":""} Jul 02 20:49:53 nnn volumio[951]: verbose: CURRENT POSITION 0 Jul 02 20:49:53 nnn volumio[951]: info: CoreStateMachine::syncState stateService play Jul 02 20:49:53 nnn volumio[951]: info: CoreStateMachine::syncState currentStatus stop Jul 02 20:49:54 nnn volumio[951]: info: ------------------------------ 86ms Jul 02 20:49:54 nnn volumio[951]: info: Jul 02 20:49:54 nnn volumio[951]: ---------------------------- MPD announces state update: player Jul 02 20:49:54 nnn volumio[951]: info: ControllerMpd::getState Jul 02 20:49:54 nnn volumio[951]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:49:54 nnn volumio[951]: info: Jul 02 20:49:54 nnn volumio[951]: ---------------------------- MPD announces state update: player Jul 02 20:49:54 nnn volumio[951]: info: ControllerMpd::getState Jul 02 20:49:54 nnn volumio[951]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:49:54 nnn volumio[951]: info: sendMpdCommand status took 42 milliseconds Jul 02 20:49:54 nnn volumio[951]: info: sendMpdCommand playlistinfo took 33 milliseconds Jul 02 20:49:54 nnn volumio[951]: info: sendMpdCommand playlistinfo took 32 milliseconds Jul 02 20:49:54 nnn volumio[951]: info: sendMpdCommand status took 17 milliseconds Jul 02 20:49:54 nnn volumio[951]: info: sendMpdCommand status took 12 milliseconds Jul 02 20:49:54 nnn volumio[951]: verbose: ControllerMpd::parseState Jul 02 20:49:54 nnn volumio[951]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:49:54 nnn volumio[951]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:49:54 nnn volumio[951]: verbose: ControllerMpd::parseState Jul 02 20:49:54 nnn volumio[951]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:49:54 nnn volumio[951]: verbose: ControllerMpd::parseState Jul 02 20:49:54 nnn volumio[951]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:49:54 nnn volumio[951]: info: ControllerMpd::pushState Jul 02 20:49:54 nnn volumio[951]: info: CoreCommandRouter::servicePushState Jul 02 20:49:54 nnn volumio[951]: verbose: In UPNP mode Jul 02 20:49:54 nnn volumio[951]: verbose: STATE SERVICE {"status":"play","position":0,"seek":605,"duration":219,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"The Killing Moon","artist":"Nouvelle Vague","album":"Bande à Part","uri":"http://m801.music.126.net/20250702211452/d1d4cfaf0a382ef59854178e1f02847d/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/29083156639/d122/98df/bc44/bd8a27e6a13b8718dbd63a8cb3a2798c.m4a?vuutv=GSd4tmQRZaK7A8Y+FhCyhp6av5lXgiR01TY8oYrPn3HqUmuXnZgvoL4jcJclrJfHFgaJQkDG06+Q8DuayCyQWFWs4lJDMXDEHarToG2BuR8=","trackType":""} Jul 02 20:49:54 nnn volumio[951]: verbose: CURRENT POSITION 0 Jul 02 20:49:54 nnn volumio[951]: info: CoreStateMachine::syncState stateService play Jul 02 20:49:54 nnn volumio[951]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:49:54 nnn volumio[951]: info: Received an update from plugin. extracting info from payload Jul 02 20:49:54 nnn volumio[951]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 02 20:49:54 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:54 nnn volumio[951]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 20:49:54 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:54 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:54 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:54 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:54 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:54 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:54 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:54 nnn volumio[951]: info: ControllerMpd::pushState Jul 02 20:49:54 nnn volumio[951]: info: CoreCommandRouter::servicePushState Jul 02 20:49:54 nnn volumio[951]: verbose: In UPNP mode Jul 02 20:49:54 nnn volumio[951]: verbose: STATE SERVICE {"status":"play","position":0,"seek":605,"duration":219,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"The Killing Moon","artist":"Nouvelle Vague","album":"Bande à Part","uri":"http://m801.music.126.net/20250702211452/d1d4cfaf0a382ef59854178e1f02847d/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/29083156639/d122/98df/bc44/bd8a27e6a13b8718dbd63a8cb3a2798c.m4a?vuutv=GSd4tmQRZaK7A8Y+FhCyhp6av5lXgiR01TY8oYrPn3HqUmuXnZgvoL4jcJclrJfHFgaJQkDG06+Q8DuayCyQWFWs4lJDMXDEHarToG2BuR8=","trackType":""} Jul 02 20:49:54 nnn volumio[951]: verbose: CURRENT POSITION 0 Jul 02 20:49:54 nnn volumio[951]: info: CoreStateMachine::syncState stateService play Jul 02 20:49:54 nnn volumio[951]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:49:54 nnn volumio[951]: info: Received an update from plugin. extracting info from payload Jul 02 20:49:54 nnn volumio[951]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 02 20:49:54 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:54 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:54 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:54 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:54 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:54 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:54 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:54 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:54 nnn volumio[951]: info: ------------------------------ 203ms Jul 02 20:49:54 nnn volumio[951]: info: ------------------------------ 191ms Jul 02 20:49:54 nnn volumio[951]: info: sendMpdCommand playlistinfo took 131 milliseconds Jul 02 20:49:54 nnn volumio[951]: info: sendMpdCommand playlistinfo took 128 milliseconds Jul 02 20:49:54 nnn volumio[951]: info: sendMpdCommand playlistinfo took 127 milliseconds Jul 02 20:49:54 nnn volumio[951]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:49:54 nnn volumio[951]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:49:54 nnn volumio[951]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:49:54 nnn volumio[951]: info: ControllerMpd::pushState Jul 02 20:49:54 nnn volumio[951]: info: CoreCommandRouter::servicePushState Jul 02 20:49:54 nnn volumio[951]: verbose: In UPNP mode Jul 02 20:49:54 nnn volumio[951]: verbose: STATE SERVICE {"status":"play","position":0,"seek":605,"duration":219,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"The Killing Moon","artist":"Nouvelle Vague","album":"Bande à Part","uri":"http://m801.music.126.net/20250702211452/d1d4cfaf0a382ef59854178e1f02847d/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/29083156639/d122/98df/bc44/bd8a27e6a13b8718dbd63a8cb3a2798c.m4a?vuutv=GSd4tmQRZaK7A8Y+FhCyhp6av5lXgiR01TY8oYrPn3HqUmuXnZgvoL4jcJclrJfHFgaJQkDG06+Q8DuayCyQWFWs4lJDMXDEHarToG2BuR8=","trackType":""} Jul 02 20:49:54 nnn volumio[951]: verbose: CURRENT POSITION 0 Jul 02 20:49:54 nnn volumio[951]: info: CoreStateMachine::syncState stateService play Jul 02 20:49:54 nnn volumio[951]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:49:54 nnn volumio[951]: info: Received an update from plugin. extracting info from payload Jul 02 20:49:54 nnn volumio[951]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 02 20:49:54 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:54 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:54 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:54 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:54 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:54 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:54 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:54 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:54 nnn volumio[951]: info: ControllerMpd::pushState Jul 02 20:49:54 nnn volumio[951]: info: CoreCommandRouter::servicePushState Jul 02 20:49:54 nnn volumio[951]: verbose: In UPNP mode Jul 02 20:49:54 nnn volumio[951]: verbose: STATE SERVICE {"status":"play","position":0,"seek":731,"duration":219,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"The Killing Moon","artist":"Nouvelle Vague","album":"Bande à Part","uri":"http://m801.music.126.net/20250702211452/d1d4cfaf0a382ef59854178e1f02847d/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/29083156639/d122/98df/bc44/bd8a27e6a13b8718dbd63a8cb3a2798c.m4a?vuutv=GSd4tmQRZaK7A8Y+FhCyhp6av5lXgiR01TY8oYrPn3HqUmuXnZgvoL4jcJclrJfHFgaJQkDG06+Q8DuayCyQWFWs4lJDMXDEHarToG2BuR8=","trackType":""} Jul 02 20:49:54 nnn volumio[951]: verbose: CURRENT POSITION 0 Jul 02 20:49:54 nnn volumio[951]: info: CoreStateMachine::syncState stateService play Jul 02 20:49:54 nnn volumio[951]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:49:54 nnn volumio[951]: info: Received an update from plugin. extracting info from payload Jul 02 20:49:54 nnn volumio[951]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 02 20:49:54 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:54 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:54 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:54 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:54 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:54 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:54 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:54 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:54 nnn volumio[951]: info: ControllerMpd::pushState Jul 02 20:49:54 nnn volumio[951]: info: CoreCommandRouter::servicePushState Jul 02 20:49:54 nnn volumio[951]: verbose: In UPNP mode Jul 02 20:49:54 nnn volumio[951]: verbose: STATE SERVICE {"status":"play","position":0,"seek":983,"duration":219,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"The Killing Moon","artist":"Nouvelle Vague","album":"Bande à Part","uri":"http://m801.music.126.net/20250702211452/d1d4cfaf0a382ef59854178e1f02847d/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/29083156639/d122/98df/bc44/bd8a27e6a13b8718dbd63a8cb3a2798c.m4a?vuutv=GSd4tmQRZaK7A8Y+FhCyhp6av5lXgiR01TY8oYrPn3HqUmuXnZgvoL4jcJclrJfHFgaJQkDG06+Q8DuayCyQWFWs4lJDMXDEHarToG2BuR8=","trackType":""} Jul 02 20:49:54 nnn volumio[951]: verbose: CURRENT POSITION 0 Jul 02 20:49:54 nnn volumio[951]: info: CoreStateMachine::syncState stateService play Jul 02 20:49:54 nnn volumio[951]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:49:54 nnn volumio[951]: info: Received an update from plugin. extracting info from payload Jul 02 20:49:54 nnn volumio[951]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 02 20:49:54 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:54 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:54 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:54 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:54 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:54 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:54 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:54 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:54 nnn volumio[951]: info: ------------------------------ 313ms Jul 02 20:49:54 nnn volumio[951]: info: ------------------------------ 285ms Jul 02 20:49:54 nnn volumio[951]: info: ------------------------------ 280ms Jul 02 20:49:54 nnn volumio[951]: info: CoreCommandRouter::volumioGetQueue Jul 02 20:49:54 nnn volumio[951]: info: CoreStateMachine::getQueue Jul 02 20:49:54 nnn volumio[951]: info: CorePlayQueue::getQueue Jul 02 20:49:54 nnn volumio[951]: info: CoreCommandRouter::volumioGetQueue Jul 02 20:49:54 nnn volumio[951]: info: CoreStateMachine::getQueue Jul 02 20:49:54 nnn volumio[951]: info: CorePlayQueue::getQueue Jul 02 20:49:54 nnn volumio[951]: info: CoreCommandRouter::volumioGetQueue Jul 02 20:49:54 nnn volumio[951]: info: CoreStateMachine::getQueue Jul 02 20:49:54 nnn volumio[951]: info: CorePlayQueue::getQueue Jul 02 20:49:54 nnn volumio[951]: info: CoreCommandRouter::volumioGetQueue Jul 02 20:49:54 nnn volumio[951]: info: CoreStateMachine::getQueue Jul 02 20:49:54 nnn volumio[951]: info: CorePlayQueue::getQueue Jul 02 20:49:54 nnn volumio[951]: info: CoreCommandRouter::volumioGetQueue Jul 02 20:49:54 nnn volumio[951]: info: CoreStateMachine::getQueue Jul 02 20:49:54 nnn volumio[951]: info: CorePlayQueue::getQueue Jul 02 20:49:54 nnn volumio[951]: info: CoreCommandRouter::volumioGetQueue Jul 02 20:49:54 nnn volumio[951]: info: CoreStateMachine::getQueue Jul 02 20:49:54 nnn volumio[951]: info: CorePlayQueue::getQueue Jul 02 20:49:54 nnn volumio[951]: info: CoreCommandRouter::volumioGetQueue Jul 02 20:49:54 nnn volumio[951]: info: CoreStateMachine::getQueue Jul 02 20:49:54 nnn volumio[951]: info: CorePlayQueue::getQueue Jul 02 20:49:54 nnn volumio[951]: info: CoreCommandRouter::volumioGetQueue Jul 02 20:49:54 nnn volumio[951]: info: CoreStateMachine::getQueue Jul 02 20:49:54 nnn volumio[951]: info: CorePlayQueue::getQueue Jul 02 20:49:54 nnn volumio[951]: info: CoreCommandRouter::volumioGetQueue Jul 02 20:49:54 nnn volumio[951]: info: CoreStateMachine::getQueue Jul 02 20:49:54 nnn volumio[951]: info: CorePlayQueue::getQueue Jul 02 20:49:54 nnn volumio[951]: info: CoreCommandRouter::volumioGetQueue Jul 02 20:49:54 nnn volumio[951]: info: CoreStateMachine::getQueue Jul 02 20:49:54 nnn volumio[951]: info: CorePlayQueue::getQueue Jul 02 20:49:54 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:49:54 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:49:54 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:49:54 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:49:54 nnn volumio[951]: error: Serial API: Failed to decode command: 100, message: undefined Jul 02 20:49:54 nnn volumio[951]: error: Serial API: Failed to decode command: MAXVOL, message: Jul 02 20:49:54 nnn volumio[951]: error: Serial API: Failed to decode command: 100, message: undefined Jul 02 20:49:54 nnn volumio[951]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Jul 02 20:49:54 nnn volumio[951]: info: CoreCommandRouter::volumioGetQueue Jul 02 20:49:54 nnn volumio[951]: info: CoreStateMachine::getQueue Jul 02 20:49:54 nnn volumio[951]: info: CorePlayQueue::getQueue Jul 02 20:50:22 nnn volumio[951]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 02 20:50:22 nnn volumio[951]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 02 20:50:22 nnn volumio[951]: info: Discovery: Getting this device information Jul 02 20:50:22 nnn volumio[951]: info: CoreCommandRouter::volumioGetState Jul 02 20:50:22 nnn volumio[951]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 02 20:50:22 nnn volumio[951]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 02 20:50:22 nnn volumio[951]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 02 20:50:22 nnn volumio[951]: info: Discovery: Getting this device information Jul 02 20:50:22 nnn volumio[951]: info: CoreCommandRouter::volumioGetState Jul 02 20:50:22 nnn volumio[951]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 02 20:50:22 nnn volumio[951]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/XG/WINTER%20WITHOUT%20YOU%20-Orchestra%20ver.-/65497230-f57e-42f8-9607-6350829da9ec.png' Jul 02 20:50:22 nnn volumio[951]: verbose: New Socket.io Connection to 192.168.50.141 from 192.168.50.129 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 6 Jul 02 20:50:22 nnn volumio[951]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 02 20:50:22 nnn volumio[951]: info: CoreCommandRouter::volumioGetVisibleSources Jul 02 20:50:22 nnn volumio[951]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 02 20:50:22 nnn volumio[951]: info: CoreCommandRouter::volumioGetState Jul 02 20:50:22 nnn volumio[951]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 02 20:50:22 nnn volumio[951]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 02 20:50:22 nnn volumio[951]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jul 02 20:50:22 nnn volumio[951]: info: Received Get System Info Jul 02 20:50:22 nnn volumio[951]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 02 20:50:22 nnn volumio[951]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 02 20:50:22 nnn volumio[951]: info: Discovery: Getting this device information Jul 02 20:50:22 nnn volumio[951]: info: CoreCommandRouter::volumioGetState Jul 02 20:50:22 nnn volumio[951]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 02 20:50:22 nnn volumio[951]: info: CoreCommandRouter::volumioGetState Jul 02 20:50:22 nnn volumio[951]: info: Listing playlists Jul 02 20:50:22 nnn volumio[951]: info: Jul 02 20:50:22 nnn volumio[951]: ---------------------------- MPD announces state update: player Jul 02 20:50:22 nnn volumio[951]: info: ControllerMpd::getState Jul 02 20:50:22 nnn volumio[951]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:50:22 nnn volumio[951]: info: Jul 02 20:50:22 nnn volumio[951]: ---------------------------- MPD announces state update: player Jul 02 20:50:22 nnn volumio[951]: info: ControllerMpd::getState Jul 02 20:50:22 nnn volumio[951]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:50:22 nnn volumio[951]: info: Jul 02 20:50:22 nnn volumio[951]: ---------------------------- MPD announces state update: player Jul 02 20:50:22 nnn volumio[951]: info: ControllerMpd::getState Jul 02 20:50:22 nnn volumio[951]: verbose: ControllerMpd::sendMpdCommand status Jul 02 20:50:22 nnn volumio[951]: info: sendMpdCommand status took 10 milliseconds Jul 02 20:50:22 nnn volumio[951]: info: sendMpdCommand status took 7 milliseconds Jul 02 20:50:22 nnn volumio[951]: info: sendMpdCommand status took 4 milliseconds Jul 02 20:50:22 nnn volumio[951]: verbose: ControllerMpd::parseState Jul 02 20:50:22 nnn volumio[951]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:50:22 nnn volumio[951]: verbose: ControllerMpd::parseState Jul 02 20:50:22 nnn volumio[951]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:50:22 nnn volumio[951]: verbose: ControllerMpd::parseState Jul 02 20:50:22 nnn volumio[951]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 20:50:22 nnn volumio[951]: info: sendMpdCommand playlistinfo took 22 milliseconds Jul 02 20:50:22 nnn volumio[951]: info: sendMpdCommand playlistinfo took 22 milliseconds Jul 02 20:50:22 nnn volumio[951]: info: sendMpdCommand playlistinfo took 21 milliseconds Jul 02 20:50:22 nnn volumio[951]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:50:22 nnn volumio[951]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:50:22 nnn volumio[951]: verbose: ControllerMpd::parseTrackInfo Jul 02 20:50:22 nnn volumio[951]: info: ControllerMpd::pushState Jul 02 20:50:22 nnn volumio[951]: info: CoreCommandRouter::servicePushState Jul 02 20:50:22 nnn volumio[951]: verbose: In UPNP mode Jul 02 20:50:22 nnn volumio[951]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":30228,"duration":219,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"The Killing Moon","artist":"Nouvelle Vague","album":"Bande à Part","uri":"http://m801.music.126.net/20250702211452/d1d4cfaf0a382ef59854178e1f02847d/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/29083156639/d122/98df/bc44/bd8a27e6a13b8718dbd63a8cb3a2798c.m4a?vuutv=GSd4tmQRZaK7A8Y+FhCyhp6av5lXgiR01TY8oYrPn3HqUmuXnZgvoL4jcJclrJfHFgaJQkDG06+Q8DuayCyQWFWs4lJDMXDEHarToG2BuR8=","trackType":""} Jul 02 20:50:22 nnn volumio[951]: verbose: CURRENT POSITION 0 Jul 02 20:50:22 nnn volumio[951]: info: CoreStateMachine::syncState stateService pause Jul 02 20:50:22 nnn volumio[951]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:50:22 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:50:22 nnn volumio[951]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 20:50:22 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:50:23 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:50:23 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:50:23 nnn volumio[951]: info: CoreStateMachine::stPlaybackTimer Jul 02 20:50:23 nnn volumio[951]: info: ControllerMpd::pushState Jul 02 20:50:23 nnn volumio[951]: info: CoreCommandRouter::servicePushState Jul 02 20:50:23 nnn volumio[951]: verbose: In UPNP mode Jul 02 20:50:23 nnn volumio[951]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":30228,"duration":219,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"The Killing Moon","artist":"Nouvelle Vague","album":"Bande à Part","uri":"http://m801.music.126.net/20250702211452/d1d4cfaf0a382ef59854178e1f02847d/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/29083156639/d122/98df/bc44/bd8a27e6a13b8718dbd63a8cb3a2798c.m4a?vuutv=GSd4tmQRZaK7A8Y+FhCyhp6av5lXgiR01TY8oYrPn3HqUmuXnZgvoL4jcJclrJfHFgaJQkDG06+Q8DuayCyQWFWs4lJDMXDEHarToG2BuR8=","trackType":""} Jul 02 20:50:23 nnn volumio[951]: verbose: CURRENT POSITION 0 Jul 02 20:50:23 nnn volumio[951]: info: CoreStateMachine::syncState stateService pause Jul 02 20:50:23 nnn volumio[951]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:50:23 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:50:23 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:50:23 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:50:23 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:50:23 nnn volumio[951]: info: CoreStateMachine::stPlaybackTimer Jul 02 20:50:23 nnn volumio[951]: info: ControllerMpd::pushState Jul 02 20:50:23 nnn volumio[951]: info: CoreCommandRouter::servicePushState Jul 02 20:50:23 nnn volumio[951]: verbose: In UPNP mode Jul 02 20:50:23 nnn volumio[951]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":30228,"duration":219,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"The Killing Moon","artist":"Nouvelle Vague","album":"Bande à Part","uri":"http://m801.music.126.net/20250702211452/d1d4cfaf0a382ef59854178e1f02847d/jdyyaac/obj/w5rDlsOJwrLDjj7CmsOj/29083156639/d122/98df/bc44/bd8a27e6a13b8718dbd63a8cb3a2798c.m4a?vuutv=GSd4tmQRZaK7A8Y+FhCyhp6av5lXgiR01TY8oYrPn3HqUmuXnZgvoL4jcJclrJfHFgaJQkDG06+Q8DuayCyQWFWs4lJDMXDEHarToG2BuR8=","trackType":""} Jul 02 20:50:23 nnn volumio[951]: verbose: CURRENT POSITION 0 Jul 02 20:50:23 nnn volumio[951]: info: CoreStateMachine::syncState stateService pause Jul 02 20:50:23 nnn volumio[951]: info: CoreStateMachine::syncState currentStatus play Jul 02 20:50:23 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:50:23 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:50:23 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:50:23 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:50:23 nnn volumio[951]: info: CoreStateMachine::stPlaybackTimer Jul 02 20:50:23 nnn volumio[951]: info: ------------------------------ 120ms Jul 02 20:50:23 nnn volumio[951]: info: ------------------------------ 119ms Jul 02 20:50:23 nnn volumio[951]: info: ------------------------------ 116ms Jul 02 20:50:23 nnn volumio[951]: info: VolumeController::SetAlsaVolume0 Jul 02 20:50:23 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:50:23 nnn volumio[951]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 20:50:23 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:50:23 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:50:23 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:50:23 nnn volumio[951]: info: CoreCommandRouter::volumioGetQueue Jul 02 20:50:23 nnn volumio[951]: info: CoreStateMachine::getQueue Jul 02 20:50:23 nnn volumio[951]: info: CorePlayQueue::getQueue Jul 02 20:50:23 nnn volumio[951]: info: CoreCommandRouter::volumioGetQueue Jul 02 20:50:23 nnn volumio[951]: info: CoreStateMachine::getQueue Jul 02 20:50:23 nnn volumio[951]: info: CorePlayQueue::getQueue Jul 02 20:50:23 nnn volumio[951]: info: CoreCommandRouter::volumioGetQueue Jul 02 20:50:23 nnn volumio[951]: info: CoreStateMachine::getQueue Jul 02 20:50:23 nnn volumio[951]: info: CorePlayQueue::getQueue Jul 02 20:50:23 nnn volumio[951]: info: CoreCommandRouter::volumioGetQueue Jul 02 20:50:23 nnn volumio[951]: info: CoreStateMachine::getQueue Jul 02 20:50:23 nnn volumio[951]: info: CorePlayQueue::getQueue Jul 02 20:50:23 nnn volumio[951]: info: Updating RAAT Signal Path Jul 02 20:50:23 nnn volumio[951]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 02 20:50:23 nnn volumio[951]: info: CoreStateMachine::pushState Jul 02 20:50:23 nnn volumio[951]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 20:50:23 nnn volumio[951]: info: CoreCommandRouter::volumioPushState Jul 02 20:50:23 nnn volumio[951]: info: MRS: Pushing multiroomSync output update for this device Jul 02 20:50:23 nnn volumio[951]: info: MRS: Pushing multiroomSync output Jul 02 20:50:23 nnn volumio[951]: error: Serial API: Failed to decode command: 100, message: undefined Jul 02 20:50:23 nnn volumio[951]: error: Serial API: Failed to decode command: MAXVOL, message: Jul 02 20:50:23 nnn volumio[951]: error: Serial API: Failed to decode command: 100, message: undefined Jul 02 20:50:23 nnn volumio[951]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Jul 02 20:50:23 nnn volumio[951]: info: CoreCommandRouter::volumioGetQueue Jul 02 20:50:23 nnn volumio[951]: info: CoreStateMachine::getQueue Jul 02 20:50:23 nnn volumio[951]: info: CorePlayQueue::getQueue Jul 02 20:50:23 nnn volumio[951]: verbose: New Socket.io Connection to 192.168.50.141:3000 from 192.168.50.129 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Jul 02 20:50:26 nnn volumio[951]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 02 20:50:26 nnn volumio[951]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jul 02 20:50:26 nnn volumio[951]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jul 02 20:50:26 nnn volumio[951]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jul 02 20:50:26 nnn volumio[951]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Jul 02 20:50:27 nnn volumio[951]: info: Received Get System Version Jul 02 20:50:27 nnn volumio[951]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 02 20:50:29 nnn volumio[951]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 02 20:50:29 nnn volumio[951]: Error: connect ETIMEDOUT 202.182.98.125:443 Jul 02 20:50:29 nnn volumio[951]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Jul 02 20:50:29 nnn volumio[951]: errno: -110, Jul 02 20:50:29 nnn volumio[951]: code: 'ETIMEDOUT', Jul 02 20:50:29 nnn volumio[951]: syscall: 'connect', Jul 02 20:50:29 nnn volumio[951]: address: '202.182.98.125', Jul 02 20:50:29 nnn volumio[951]: port: 443 Jul 02 20:50:29 nnn volumio[951]: } Jul 02 20:50:29 nnn volumio[951]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 02 20:50:30 nnn sudo[2766]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-02 20:49 Jul 02 20:50:30 nnn sudo[2766]: 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="723ba1d50268911818ebdca474fb7656df6050cb" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="smsl" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 14 Feb 2025 02:46:15 PM CET" VOLUMIO_VERSION="3.789" VOLUMIO_HARDWARE="nanopineo3" VOLUMIO_DEVICENAME="Nanopi Neo3" VOLUMIO_VENDOR_MODEL="SMSL S100" VOLUMIO_VENDOR="SMSL" VOLUMIO_MODEL="S100" VOLUMIO_HASH="215d731aee688e00fb3cc923952be9a7"