-- Logs begin at Thu 2019-02-14 10:11:59 GMT, end at Tue 2025-04-15 15:32:38 BST. -- Apr 15 15:31:07 smsl-n100 volumio[1093]: verbose: New Socket.io Connection to 192.168.5.172 from 192.168.5.67 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3_2 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 6 Apr 15 15:31:08 smsl-n100 volumio[1093]: info: Received Get System Info Apr 15 15:31:08 smsl-n100 volumio[1093]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 15 15:31:08 smsl-n100 volumio[1093]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 15 15:31:08 smsl-n100 volumio[1093]: info: Discovery: Getting this device information Apr 15 15:31:08 smsl-n100 volumio[1093]: info: CoreCommandRouter::volumioGetState Apr 15 15:31:08 smsl-n100 volumio[1093]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 15 15:31:08 smsl-n100 volumio[1093]: info: CoreCommandRouter::volumioGetState Apr 15 15:31:08 smsl-n100 volumio[1093]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 15 15:31:08 smsl-n100 volumio[1093]: info: CoreCommandRouter::volumioGetVisibleSources Apr 15 15:31:08 smsl-n100 volumio[1093]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 15 15:31:08 smsl-n100 volumio[1093]: info: CoreCommandRouter::volumioGetState Apr 15 15:31:08 smsl-n100 volumio[1093]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 15 15:31:08 smsl-n100 volumio[1093]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 15 15:31:08 smsl-n100 volumio[1093]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Apr 15 15:31:08 smsl-n100 volumio[1093]: info: Received Get System Info Apr 15 15:31:08 smsl-n100 volumio[1093]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 15 15:31:08 smsl-n100 volumio[1093]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 15 15:31:08 smsl-n100 volumio[1093]: info: Discovery: Getting this device information Apr 15 15:31:08 smsl-n100 volumio[1093]: info: CoreCommandRouter::volumioGetState Apr 15 15:31:08 smsl-n100 volumio[1093]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 15 15:31:08 smsl-n100 volumio[1093]: info: CoreCommandRouter::volumioGetState Apr 15 15:31:08 smsl-n100 volumio[1093]: info: Listing playlists Apr 15 15:31:08 smsl-n100 volumio[1093]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 15 15:31:10 smsl-n100 volumio[1093]: info: CoreCommandRouter::volumioPlay Apr 15 15:31:10 smsl-n100 volumio[1093]: info: CoreStateMachine::play index undefined Apr 15 15:31:10 smsl-n100 volumio[1093]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 15 15:31:10 smsl-n100 volumio[1093]: info: CorePlayQueue::getTrack 0 Apr 15 15:31:10 smsl-n100 volumio[1093]: info: CoreStateMachine::startPlaybackTimer Apr 15 15:31:10 smsl-n100 volumio[1093]: info: CorePlayQueue::getTrack 0 Apr 15 15:31:10 smsl-n100 volumio[1093]: info: [1744727470984] ControllerQobuz::resume Apr 15 15:31:10 smsl-n100 volumio[1093]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 15 15:31:10 smsl-n100 volumio[1093]: info: ControllerMpd::resume Apr 15 15:31:10 smsl-n100 volumio[1093]: verbose: ControllerMpd::sendMpdCommand play Apr 15 15:31:11 smsl-n100 volumio[1093]: info: Apr 15 15:31:11 smsl-n100 volumio[1093]: ---------------------------- MPD announces state update: player Apr 15 15:31:11 smsl-n100 volumio[1093]: info: sendMpdCommand play took 24 milliseconds Apr 15 15:31:11 smsl-n100 volumio[1093]: info: ControllerMpd::getState Apr 15 15:31:11 smsl-n100 volumio[1093]: verbose: ControllerMpd::sendMpdCommand status Apr 15 15:31:11 smsl-n100 volumio[1093]: info: sendMpdCommand status took 4 milliseconds Apr 15 15:31:11 smsl-n100 volumio[1093]: verbose: ControllerMpd::parseState Apr 15 15:31:11 smsl-n100 volumio[1093]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 15:31:11 smsl-n100 volumio[1093]: info: Apr 15 15:31:11 smsl-n100 volumio[1093]: ---------------------------- MPD announces state update: player Apr 15 15:31:11 smsl-n100 volumio[1093]: info: ControllerMpd::getState Apr 15 15:31:11 smsl-n100 volumio[1093]: verbose: ControllerMpd::sendMpdCommand status Apr 15 15:31:11 smsl-n100 volumio[1093]: info: Apr 15 15:31:11 smsl-n100 volumio[1093]: ---------------------------- MPD announces state update: player Apr 15 15:31:11 smsl-n100 volumio[1093]: info: ControllerMpd::getState Apr 15 15:31:11 smsl-n100 volumio[1093]: verbose: ControllerMpd::sendMpdCommand status Apr 15 15:31:11 smsl-n100 volumio[1093]: info: sendMpdCommand playlistinfo took 19 milliseconds Apr 15 15:31:11 smsl-n100 volumio[1093]: info: sendMpdCommand status took 20 milliseconds Apr 15 15:31:11 smsl-n100 volumio[1093]: info: sendMpdCommand status took 14 milliseconds Apr 15 15:31:11 smsl-n100 volumio[1093]: verbose: ControllerMpd::parseTrackInfo Apr 15 15:31:11 smsl-n100 volumio[1093]: verbose: ControllerMpd::parseState Apr 15 15:31:11 smsl-n100 volumio[1093]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 15:31:11 smsl-n100 volumio[1093]: verbose: ControllerMpd::parseState Apr 15 15:31:11 smsl-n100 volumio[1093]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 15:31:11 smsl-n100 volumio[1093]: info: ControllerMpd::pushState Apr 15 15:31:11 smsl-n100 volumio[1093]: info: CoreCommandRouter::servicePushState Apr 15 15:31:11 smsl-n100 volumio[1093]: info: CorePlayQueue::getTrack 0 Apr 15 15:31:11 smsl-n100 volumio[1093]: verbose: STATE SERVICE {"status":"play","position":0,"seek":84351,"duration":301,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2904 Kbps","isStreaming":false,"title":"10473970","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/10473970","trackType":"qobuz"} Apr 15 15:31:11 smsl-n100 volumio[1093]: verbose: CURRENT POSITION 0 Apr 15 15:31:11 smsl-n100 volumio[1093]: info: CoreStateMachine::syncState stateService play Apr 15 15:31:11 smsl-n100 volumio[1093]: info: CoreStateMachine::syncState currentStatus pause Apr 15 15:31:11 smsl-n100 volumio[1093]: info: CoreStateMachine::pushState Apr 15 15:31:11 smsl-n100 volumio[1093]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 15:31:11 smsl-n100 volumio[1093]: info: CoreCommandRouter::volumioPushState Apr 15 15:31:11 smsl-n100 volumio[1093]: info: MRS: Pushing multiroomSync output update for this device Apr 15 15:31:11 smsl-n100 volumio[1093]: info: MRS: Pushing multiroomSync output Apr 15 15:31:11 smsl-n100 volumio[1093]: info: ------------------------------ 117ms Apr 15 15:31:11 smsl-n100 volumio[1093]: info: sendMpdCommand playlistinfo took 73 milliseconds Apr 15 15:31:11 smsl-n100 volumio[1093]: info: sendMpdCommand playlistinfo took 72 milliseconds Apr 15 15:31:11 smsl-n100 volumio[1093]: verbose: ControllerMpd::parseTrackInfo Apr 15 15:31:11 smsl-n100 volumio[1093]: verbose: ControllerMpd::parseTrackInfo Apr 15 15:31:11 smsl-n100 volumio[1093]: info: ControllerMpd::pushState Apr 15 15:31:11 smsl-n100 volumio[1093]: info: CoreCommandRouter::servicePushState Apr 15 15:31:11 smsl-n100 volumio[1093]: info: CorePlayQueue::getTrack 0 Apr 15 15:31:11 smsl-n100 volumio[1093]: verbose: STATE SERVICE {"status":"play","position":0,"seek":84351,"duration":301,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2904 Kbps","isStreaming":false,"title":"10473970","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/10473970","trackType":"qobuz"} Apr 15 15:31:11 smsl-n100 volumio[1093]: verbose: CURRENT POSITION 0 Apr 15 15:31:11 smsl-n100 volumio[1093]: info: CoreStateMachine::syncState stateService play Apr 15 15:31:11 smsl-n100 volumio[1093]: info: CoreStateMachine::syncState currentStatus play Apr 15 15:31:11 smsl-n100 volumio[1093]: info: Received an update from plugin. extracting info from payload Apr 15 15:31:11 smsl-n100 volumio[1093]: info: CoreStateMachine::pushState Apr 15 15:31:11 smsl-n100 volumio[1093]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 15:31:11 smsl-n100 volumio[1093]: info: CoreCommandRouter::volumioPushState Apr 15 15:31:11 smsl-n100 volumio[1093]: info: MRS: Pushing multiroomSync output update for this device Apr 15 15:31:11 smsl-n100 volumio[1093]: info: MRS: Pushing multiroomSync output Apr 15 15:31:11 smsl-n100 volumio[1093]: info: CoreStateMachine::pushState Apr 15 15:31:11 smsl-n100 volumio[1093]: info: CoreCommandRouter::volumioPushState Apr 15 15:31:11 smsl-n100 volumio[1093]: info: MRS: Pushing multiroomSync output update for this device Apr 15 15:31:11 smsl-n100 volumio[1093]: info: MRS: Pushing multiroomSync output Apr 15 15:31:11 smsl-n100 volumio[1093]: info: ControllerMpd::pushState Apr 15 15:31:11 smsl-n100 volumio[1093]: info: CoreCommandRouter::servicePushState Apr 15 15:31:11 smsl-n100 volumio[1093]: info: CorePlayQueue::getTrack 0 Apr 15 15:31:11 smsl-n100 volumio[1093]: verbose: STATE SERVICE {"status":"play","position":0,"seek":84351,"duration":301,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2904 Kbps","isStreaming":false,"title":"10473970","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/10473970","trackType":"qobuz"} Apr 15 15:31:11 smsl-n100 volumio[1093]: verbose: CURRENT POSITION 0 Apr 15 15:31:11 smsl-n100 volumio[1093]: info: CoreStateMachine::syncState stateService play Apr 15 15:31:11 smsl-n100 volumio[1093]: info: CoreStateMachine::syncState currentStatus play Apr 15 15:31:11 smsl-n100 volumio[1093]: info: Received an update from plugin. extracting info from payload Apr 15 15:31:11 smsl-n100 volumio[1093]: info: CoreStateMachine::pushState Apr 15 15:31:11 smsl-n100 volumio[1093]: info: CoreCommandRouter::volumioPushState Apr 15 15:31:11 smsl-n100 volumio[1093]: info: MRS: Pushing multiroomSync output update for this device Apr 15 15:31:11 smsl-n100 volumio[1093]: info: MRS: Pushing multiroomSync output Apr 15 15:31:11 smsl-n100 volumio[1093]: info: CoreStateMachine::pushState Apr 15 15:31:11 smsl-n100 volumio[1093]: info: CoreCommandRouter::volumioPushState Apr 15 15:31:11 smsl-n100 volumio[1093]: info: MRS: Pushing multiroomSync output update for this device Apr 15 15:31:11 smsl-n100 volumio[1093]: info: MRS: Pushing multiroomSync output Apr 15 15:31:11 smsl-n100 volumio[1093]: info: ------------------------------ 323ms Apr 15 15:31:11 smsl-n100 volumio[1093]: info: ------------------------------ 318ms Apr 15 15:31:11 smsl-n100 volumio[1093]: info: CoreStateMachine::pushState Apr 15 15:31:11 smsl-n100 volumio[1093]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 15:31:11 smsl-n100 volumio[1093]: info: CoreCommandRouter::volumioPushState Apr 15 15:31:11 smsl-n100 volumio[1093]: info: MRS: Pushing multiroomSync output update for this device Apr 15 15:31:11 smsl-n100 volumio[1093]: info: MRS: Pushing multiroomSync output Apr 15 15:31:11 smsl-n100 volumio[1093]: error: Serial API: Failed to decode command: 100, message: undefined Apr 15 15:31:11 smsl-n100 volumio[1093]: error: Serial API: Failed to decode command: MAXVOL, message: Apr 15 15:31:11 smsl-n100 volumio[1093]: error: Serial API: Failed to decode command: 100, message: undefined Apr 15 15:31:11 smsl-n100 volumio[1093]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Apr 15 15:31:17 smsl-n100 volumio[1093]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 15 15:31:19 smsl-n100 volumio[1093]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 15 15:31:19 smsl-n100 volumio[1093]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 15 15:31:19 smsl-n100 volumio[1093]: info: Discovery: Getting this device information Apr 15 15:31:19 smsl-n100 volumio[1093]: info: CoreCommandRouter::volumioGetState Apr 15 15:31:19 smsl-n100 volumio[1093]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 15 15:31:23 smsl-n100 volumio[1093]: info: CoreCommandRouter::volumioPause Apr 15 15:31:23 smsl-n100 volumio[1093]: info: CoreStateMachine::pause Apr 15 15:31:23 smsl-n100 volumio[1093]: info: CoreStateMachine::stPlaybackTimer Apr 15 15:31:23 smsl-n100 volumio[1093]: info: CoreStateMachine::servicePause Apr 15 15:31:23 smsl-n100 volumio[1093]: info: CorePlayQueue::getTrack 0 Apr 15 15:31:23 smsl-n100 volumio[1093]: info: CoreCommandRouter::servicePause Apr 15 15:31:23 smsl-n100 volumio[1093]: info: [1744727483667] ControllerQobuz::pause Apr 15 15:31:23 smsl-n100 volumio[1093]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 15 15:31:23 smsl-n100 volumio[1093]: info: ControllerMpd::pause Apr 15 15:31:23 smsl-n100 volumio[1093]: verbose: ControllerMpd::sendMpdCommand pause Apr 15 15:31:23 smsl-n100 volumio[1093]: info: sendMpdCommand pause took 4 milliseconds Apr 15 15:31:23 smsl-n100 volumio[1093]: info: Apr 15 15:31:23 smsl-n100 volumio[1093]: ---------------------------- MPD announces state update: player Apr 15 15:31:23 smsl-n100 volumio[1093]: info: ControllerMpd::getState Apr 15 15:31:23 smsl-n100 volumio[1093]: verbose: ControllerMpd::sendMpdCommand status Apr 15 15:31:23 smsl-n100 volumio[1093]: info: Apr 15 15:31:23 smsl-n100 volumio[1093]: ---------------------------- MPD announces state update: player Apr 15 15:31:23 smsl-n100 volumio[1093]: info: ControllerMpd::getState Apr 15 15:31:23 smsl-n100 volumio[1093]: verbose: ControllerMpd::sendMpdCommand status Apr 15 15:31:23 smsl-n100 volumio[1093]: info: Apr 15 15:31:23 smsl-n100 volumio[1093]: ---------------------------- MPD announces state update: player Apr 15 15:31:23 smsl-n100 volumio[1093]: info: ControllerMpd::getState Apr 15 15:31:23 smsl-n100 volumio[1093]: verbose: ControllerMpd::sendMpdCommand status Apr 15 15:31:23 smsl-n100 volumio[1093]: info: sendMpdCommand status took 15 milliseconds Apr 15 15:31:23 smsl-n100 volumio[1093]: info: sendMpdCommand status took 10 milliseconds Apr 15 15:31:23 smsl-n100 volumio[1093]: info: sendMpdCommand status took 6 milliseconds Apr 15 15:31:23 smsl-n100 volumio[1093]: verbose: ControllerMpd::parseState Apr 15 15:31:23 smsl-n100 volumio[1093]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 15:31:23 smsl-n100 volumio[1093]: verbose: ControllerMpd::parseState Apr 15 15:31:23 smsl-n100 volumio[1093]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 15:31:23 smsl-n100 volumio[1093]: verbose: ControllerMpd::parseState Apr 15 15:31:23 smsl-n100 volumio[1093]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 15:31:23 smsl-n100 volumio[1093]: info: sendMpdCommand playlistinfo took 9 milliseconds Apr 15 15:31:23 smsl-n100 volumio[1093]: info: sendMpdCommand playlistinfo took 8 milliseconds Apr 15 15:31:23 smsl-n100 volumio[1093]: info: sendMpdCommand playlistinfo took 7 milliseconds Apr 15 15:31:23 smsl-n100 volumio[1093]: verbose: ControllerMpd::parseTrackInfo Apr 15 15:31:23 smsl-n100 volumio[1093]: verbose: ControllerMpd::parseTrackInfo Apr 15 15:31:23 smsl-n100 volumio[1093]: verbose: ControllerMpd::parseTrackInfo Apr 15 15:31:23 smsl-n100 volumio[1093]: info: ControllerMpd::pushState Apr 15 15:31:23 smsl-n100 volumio[1093]: info: CoreCommandRouter::servicePushState Apr 15 15:31:23 smsl-n100 volumio[1093]: info: CorePlayQueue::getTrack 0 Apr 15 15:31:23 smsl-n100 volumio[1093]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":97114,"duration":301,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2972 Kbps","isStreaming":false,"title":"10473970","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/10473970","trackType":"qobuz"} Apr 15 15:31:23 smsl-n100 volumio[1093]: verbose: CURRENT POSITION 0 Apr 15 15:31:23 smsl-n100 volumio[1093]: info: CoreStateMachine::syncState stateService pause Apr 15 15:31:23 smsl-n100 volumio[1093]: info: CoreStateMachine::syncState currentStatus pause Apr 15 15:31:23 smsl-n100 volumio[1093]: info: CoreStateMachine::pushState Apr 15 15:31:23 smsl-n100 volumio[1093]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 15:31:23 smsl-n100 volumio[1093]: info: CoreCommandRouter::volumioPushState Apr 15 15:31:23 smsl-n100 volumio[1093]: info: MRS: Pushing multiroomSync output update for this device Apr 15 15:31:23 smsl-n100 volumio[1093]: info: MRS: Pushing multiroomSync output Apr 15 15:31:23 smsl-n100 volumio[1093]: info: CoreStateMachine::stPlaybackTimer Apr 15 15:31:23 smsl-n100 volumio[1093]: info: ControllerMpd::pushState Apr 15 15:31:23 smsl-n100 volumio[1093]: info: CoreCommandRouter::servicePushState Apr 15 15:31:23 smsl-n100 volumio[1093]: info: CorePlayQueue::getTrack 0 Apr 15 15:31:23 smsl-n100 volumio[1093]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":97114,"duration":301,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2972 Kbps","isStreaming":false,"title":"10473970","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/10473970","trackType":"qobuz"} Apr 15 15:31:23 smsl-n100 volumio[1093]: verbose: CURRENT POSITION 0 Apr 15 15:31:23 smsl-n100 volumio[1093]: info: CoreStateMachine::syncState stateService pause Apr 15 15:31:23 smsl-n100 volumio[1093]: info: CoreStateMachine::syncState currentStatus pause Apr 15 15:31:23 smsl-n100 volumio[1093]: info: CoreStateMachine::pushState Apr 15 15:31:23 smsl-n100 volumio[1093]: info: CoreCommandRouter::volumioPushState Apr 15 15:31:23 smsl-n100 volumio[1093]: info: MRS: Pushing multiroomSync output update for this device Apr 15 15:31:23 smsl-n100 volumio[1093]: info: MRS: Pushing multiroomSync output Apr 15 15:31:23 smsl-n100 volumio[1093]: info: CoreStateMachine::stPlaybackTimer Apr 15 15:31:23 smsl-n100 volumio[1093]: info: ControllerMpd::pushState Apr 15 15:31:23 smsl-n100 volumio[1093]: info: CoreCommandRouter::servicePushState Apr 15 15:31:23 smsl-n100 volumio[1093]: info: CorePlayQueue::getTrack 0 Apr 15 15:31:23 smsl-n100 volumio[1093]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":97114,"duration":301,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2972 Kbps","isStreaming":false,"title":"10473970","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/10473970","trackType":"qobuz"} Apr 15 15:31:23 smsl-n100 volumio[1093]: verbose: CURRENT POSITION 0 Apr 15 15:31:23 smsl-n100 volumio[1093]: info: CoreStateMachine::syncState stateService pause Apr 15 15:31:23 smsl-n100 volumio[1093]: info: CoreStateMachine::syncState currentStatus pause Apr 15 15:31:23 smsl-n100 volumio[1093]: info: CoreStateMachine::pushState Apr 15 15:31:23 smsl-n100 volumio[1093]: info: CoreCommandRouter::volumioPushState Apr 15 15:31:23 smsl-n100 volumio[1093]: info: MRS: Pushing multiroomSync output update for this device Apr 15 15:31:23 smsl-n100 volumio[1093]: info: MRS: Pushing multiroomSync output Apr 15 15:31:23 smsl-n100 volumio[1093]: info: CoreStateMachine::stPlaybackTimer Apr 15 15:31:23 smsl-n100 volumio[1093]: info: ------------------------------ 203ms Apr 15 15:31:23 smsl-n100 volumio[1093]: info: ------------------------------ 199ms Apr 15 15:31:23 smsl-n100 volumio[1093]: info: ------------------------------ 196ms Apr 15 15:31:23 smsl-n100 volumio[1093]: info: CoreStateMachine::pushState Apr 15 15:31:23 smsl-n100 volumio[1093]: info: CoreCommandRouter::volumioPushState Apr 15 15:31:23 smsl-n100 volumio[1093]: info: MRS: Pushing multiroomSync output update for this device Apr 15 15:31:23 smsl-n100 volumio[1093]: info: MRS: Pushing multiroomSync output Apr 15 15:31:23 smsl-n100 volumio[1093]: error: Serial API: Failed to decode command: 100, message: undefined Apr 15 15:31:23 smsl-n100 volumio[1093]: error: Serial API: Failed to decode command: MAXVOL, message: Apr 15 15:31:23 smsl-n100 volumio[1093]: error: Serial API: Failed to decode command: 100, message: undefined Apr 15 15:31:23 smsl-n100 volumio[1093]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Apr 15 15:31:37 smsl-n100 volumio[1093]: info: CoreCommandRouter::volumioPlay Apr 15 15:31:37 smsl-n100 volumio[1093]: info: CoreStateMachine::play index undefined Apr 15 15:31:37 smsl-n100 volumio[1093]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 15 15:31:37 smsl-n100 volumio[1093]: info: CorePlayQueue::getTrack 0 Apr 15 15:31:37 smsl-n100 volumio[1093]: info: CoreStateMachine::startPlaybackTimer Apr 15 15:31:37 smsl-n100 volumio[1093]: info: CorePlayQueue::getTrack 0 Apr 15 15:31:37 smsl-n100 volumio[1093]: info: [1744727497548] ControllerQobuz::resume Apr 15 15:31:37 smsl-n100 volumio[1093]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 15 15:31:37 smsl-n100 volumio[1093]: info: ControllerMpd::resume Apr 15 15:31:37 smsl-n100 volumio[1093]: verbose: ControllerMpd::sendMpdCommand play Apr 15 15:31:37 smsl-n100 volumio[1093]: info: sendMpdCommand play took 20 milliseconds Apr 15 15:31:37 smsl-n100 volumio[1093]: info: Apr 15 15:31:37 smsl-n100 volumio[1093]: ---------------------------- MPD announces state update: player Apr 15 15:31:37 smsl-n100 volumio[1093]: info: ControllerMpd::getState Apr 15 15:31:37 smsl-n100 volumio[1093]: verbose: ControllerMpd::sendMpdCommand status Apr 15 15:31:37 smsl-n100 volumio[1093]: info: Apr 15 15:31:37 smsl-n100 volumio[1093]: ---------------------------- MPD announces state update: player Apr 15 15:31:37 smsl-n100 volumio[1093]: info: ControllerMpd::getState Apr 15 15:31:37 smsl-n100 volumio[1093]: verbose: ControllerMpd::sendMpdCommand status Apr 15 15:31:37 smsl-n100 volumio[1093]: info: Apr 15 15:31:37 smsl-n100 volumio[1093]: ---------------------------- MPD announces state update: player Apr 15 15:31:37 smsl-n100 volumio[1093]: info: ControllerMpd::getState Apr 15 15:31:37 smsl-n100 volumio[1093]: verbose: ControllerMpd::sendMpdCommand status Apr 15 15:31:37 smsl-n100 volumio[1093]: info: sendMpdCommand status took 20 milliseconds Apr 15 15:31:37 smsl-n100 volumio[1093]: info: sendMpdCommand status took 10 milliseconds Apr 15 15:31:37 smsl-n100 volumio[1093]: verbose: ControllerMpd::parseState Apr 15 15:31:37 smsl-n100 volumio[1093]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 15:31:37 smsl-n100 volumio[1093]: verbose: ControllerMpd::parseState Apr 15 15:31:37 smsl-n100 volumio[1093]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 15:31:37 smsl-n100 volumio[1093]: info: sendMpdCommand status took 12 milliseconds Apr 15 15:31:37 smsl-n100 volumio[1093]: info: sendMpdCommand playlistinfo took 7 milliseconds Apr 15 15:31:37 smsl-n100 volumio[1093]: info: sendMpdCommand playlistinfo took 6 milliseconds Apr 15 15:31:37 smsl-n100 volumio[1093]: verbose: ControllerMpd::parseState Apr 15 15:31:37 smsl-n100 volumio[1093]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 15:31:37 smsl-n100 volumio[1093]: verbose: ControllerMpd::parseTrackInfo Apr 15 15:31:37 smsl-n100 volumio[1093]: verbose: ControllerMpd::parseTrackInfo Apr 15 15:31:37 smsl-n100 volumio[1093]: info: ControllerMpd::pushState Apr 15 15:31:37 smsl-n100 volumio[1093]: info: CoreCommandRouter::servicePushState Apr 15 15:31:37 smsl-n100 volumio[1093]: info: CorePlayQueue::getTrack 0 Apr 15 15:31:37 smsl-n100 volumio[1093]: verbose: STATE SERVICE {"status":"play","position":0,"seek":98043,"duration":301,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2980 Kbps","isStreaming":false,"title":"10473970","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/10473970","trackType":"qobuz"} Apr 15 15:31:37 smsl-n100 volumio[1093]: verbose: CURRENT POSITION 0 Apr 15 15:31:37 smsl-n100 volumio[1093]: info: CoreStateMachine::syncState stateService play Apr 15 15:31:37 smsl-n100 volumio[1093]: info: CoreStateMachine::syncState currentStatus pause Apr 15 15:31:37 smsl-n100 volumio[1093]: info: CoreStateMachine::pushState Apr 15 15:31:37 smsl-n100 volumio[1093]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 15:31:37 smsl-n100 volumio[1093]: info: CoreCommandRouter::volumioPushState Apr 15 15:31:37 smsl-n100 volumio[1093]: info: MRS: Pushing multiroomSync output update for this device Apr 15 15:31:37 smsl-n100 volumio[1093]: info: MRS: Pushing multiroomSync output Apr 15 15:31:37 smsl-n100 volumio[1093]: info: ControllerMpd::pushState Apr 15 15:31:37 smsl-n100 volumio[1093]: info: CoreCommandRouter::servicePushState Apr 15 15:31:37 smsl-n100 volumio[1093]: info: CorePlayQueue::getTrack 0 Apr 15 15:31:37 smsl-n100 volumio[1093]: verbose: STATE SERVICE {"status":"play","position":0,"seek":98358,"duration":301,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2800 Kbps","isStreaming":false,"title":"10473970","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/10473970","trackType":"qobuz"} Apr 15 15:31:37 smsl-n100 volumio[1093]: verbose: CURRENT POSITION 0 Apr 15 15:31:37 smsl-n100 volumio[1093]: info: CoreStateMachine::syncState stateService play Apr 15 15:31:37 smsl-n100 volumio[1093]: info: CoreStateMachine::syncState currentStatus play Apr 15 15:31:37 smsl-n100 volumio[1093]: info: Received an update from plugin. extracting info from payload Apr 15 15:31:37 smsl-n100 volumio[1093]: info: CoreStateMachine::pushState Apr 15 15:31:37 smsl-n100 volumio[1093]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 15:31:37 smsl-n100 volumio[1093]: info: CoreCommandRouter::volumioPushState Apr 15 15:31:37 smsl-n100 volumio[1093]: info: MRS: Pushing multiroomSync output update for this device Apr 15 15:31:37 smsl-n100 volumio[1093]: info: MRS: Pushing multiroomSync output Apr 15 15:31:37 smsl-n100 volumio[1093]: info: CoreStateMachine::pushState Apr 15 15:31:37 smsl-n100 volumio[1093]: info: CoreCommandRouter::volumioPushState Apr 15 15:31:37 smsl-n100 volumio[1093]: info: MRS: Pushing multiroomSync output update for this device Apr 15 15:31:37 smsl-n100 volumio[1093]: info: MRS: Pushing multiroomSync output Apr 15 15:31:37 smsl-n100 volumio[1093]: info: ------------------------------ 210ms Apr 15 15:31:37 smsl-n100 volumio[1093]: info: ------------------------------ 204ms Apr 15 15:31:37 smsl-n100 volumio[1093]: info: sendMpdCommand playlistinfo took 175 milliseconds Apr 15 15:31:37 smsl-n100 volumio[1093]: verbose: ControllerMpd::parseTrackInfo Apr 15 15:31:37 smsl-n100 volumio[1093]: info: ControllerMpd::pushState Apr 15 15:31:37 smsl-n100 volumio[1093]: info: CoreCommandRouter::servicePushState Apr 15 15:31:37 smsl-n100 volumio[1093]: info: CorePlayQueue::getTrack 0 Apr 15 15:31:37 smsl-n100 volumio[1093]: verbose: STATE SERVICE {"status":"play","position":0,"seek":98358,"duration":301,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2800 Kbps","isStreaming":false,"title":"10473970","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/10473970","trackType":"qobuz"} Apr 15 15:31:37 smsl-n100 volumio[1093]: verbose: CURRENT POSITION 0 Apr 15 15:31:37 smsl-n100 volumio[1093]: info: CoreStateMachine::syncState stateService play Apr 15 15:31:37 smsl-n100 volumio[1093]: info: CoreStateMachine::syncState currentStatus play Apr 15 15:31:37 smsl-n100 volumio[1093]: info: Received an update from plugin. extracting info from payload Apr 15 15:31:37 smsl-n100 volumio[1093]: info: CoreStateMachine::pushState Apr 15 15:31:37 smsl-n100 volumio[1093]: info: CoreCommandRouter::volumioPushState Apr 15 15:31:37 smsl-n100 volumio[1093]: info: MRS: Pushing multiroomSync output update for this device Apr 15 15:31:37 smsl-n100 volumio[1093]: info: MRS: Pushing multiroomSync output Apr 15 15:31:37 smsl-n100 volumio[1093]: info: CoreStateMachine::pushState Apr 15 15:31:37 smsl-n100 volumio[1093]: info: CoreCommandRouter::volumioPushState Apr 15 15:31:37 smsl-n100 volumio[1093]: info: MRS: Pushing multiroomSync output update for this device Apr 15 15:31:37 smsl-n100 volumio[1093]: info: MRS: Pushing multiroomSync output Apr 15 15:31:37 smsl-n100 volumio[1093]: info: ------------------------------ 301ms Apr 15 15:31:37 smsl-n100 volumio[1093]: info: CoreStateMachine::pushState Apr 15 15:31:37 smsl-n100 volumio[1093]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 15:31:37 smsl-n100 volumio[1093]: info: CoreCommandRouter::volumioPushState Apr 15 15:31:37 smsl-n100 volumio[1093]: info: MRS: Pushing multiroomSync output update for this device Apr 15 15:31:37 smsl-n100 volumio[1093]: info: MRS: Pushing multiroomSync output Apr 15 15:31:38 smsl-n100 volumio[1093]: error: Serial API: Failed to decode command: 100, message: undefined Apr 15 15:31:38 smsl-n100 volumio[1093]: error: Serial API: Failed to decode command: MAXVOL, message: Apr 15 15:31:38 smsl-n100 volumio[1093]: error: Serial API: Failed to decode command: 100, message: undefined Apr 15 15:31:38 smsl-n100 volumio[1093]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Apr 15 15:31:39 smsl-n100 volumio[1093]: info: VolumeController::SetAlsaVolume+ Apr 15 15:31:39 smsl-n100 volumio[1093]: info: CoreStateMachine::pushState Apr 15 15:31:39 smsl-n100 volumio[1093]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 15:31:39 smsl-n100 volumio[1093]: info: CoreCommandRouter::volumioPushState Apr 15 15:31:39 smsl-n100 volumio[1093]: info: MRS: Pushing multiroomSync output update for this device Apr 15 15:31:39 smsl-n100 volumio[1093]: info: MRS: Pushing multiroomSync output Apr 15 15:31:39 smsl-n100 volumio[1093]: info: VolumeController::SetAlsaVolume+ Apr 15 15:31:39 smsl-n100 volumio[1093]: info: CoreStateMachine::pushState Apr 15 15:31:39 smsl-n100 volumio[1093]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 15:31:40 smsl-n100 volumio[1093]: info: CoreCommandRouter::volumioPushState Apr 15 15:31:40 smsl-n100 volumio[1093]: info: MRS: Pushing multiroomSync output update for this device Apr 15 15:31:40 smsl-n100 volumio[1093]: info: MRS: Pushing multiroomSync output Apr 15 15:31:47 smsl-n100 volumio[1093]: info: CoreCommandRouter::volumioPause Apr 15 15:31:47 smsl-n100 volumio[1093]: info: CoreStateMachine::pause Apr 15 15:31:47 smsl-n100 volumio[1093]: info: CoreStateMachine::stPlaybackTimer Apr 15 15:31:47 smsl-n100 volumio[1093]: info: CoreStateMachine::servicePause Apr 15 15:31:47 smsl-n100 volumio[1093]: info: CorePlayQueue::getTrack 0 Apr 15 15:31:47 smsl-n100 volumio[1093]: info: CoreCommandRouter::servicePause Apr 15 15:31:47 smsl-n100 volumio[1093]: info: [1744727507010] ControllerQobuz::pause Apr 15 15:31:47 smsl-n100 volumio[1093]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 15 15:31:47 smsl-n100 volumio[1093]: info: ControllerMpd::pause Apr 15 15:31:47 smsl-n100 volumio[1093]: verbose: ControllerMpd::sendMpdCommand pause Apr 15 15:31:47 smsl-n100 volumio[1093]: info: Apr 15 15:31:47 smsl-n100 volumio[1093]: ---------------------------- MPD announces state update: player Apr 15 15:31:47 smsl-n100 volumio[1093]: info: sendMpdCommand pause took 9 milliseconds Apr 15 15:31:47 smsl-n100 volumio[1093]: info: ControllerMpd::getState Apr 15 15:31:47 smsl-n100 volumio[1093]: verbose: ControllerMpd::sendMpdCommand status Apr 15 15:31:47 smsl-n100 volumio[1093]: info: Apr 15 15:31:47 smsl-n100 volumio[1093]: ---------------------------- MPD announces state update: player Apr 15 15:31:47 smsl-n100 volumio[1093]: info: ControllerMpd::getState Apr 15 15:31:47 smsl-n100 volumio[1093]: verbose: ControllerMpd::sendMpdCommand status Apr 15 15:31:47 smsl-n100 volumio[1093]: info: Apr 15 15:31:47 smsl-n100 volumio[1093]: ---------------------------- MPD announces state update: player Apr 15 15:31:47 smsl-n100 volumio[1093]: info: ControllerMpd::getState Apr 15 15:31:47 smsl-n100 volumio[1093]: verbose: ControllerMpd::sendMpdCommand status Apr 15 15:31:47 smsl-n100 volumio[1093]: info: sendMpdCommand status took 18 milliseconds Apr 15 15:31:47 smsl-n100 volumio[1093]: info: sendMpdCommand status took 12 milliseconds Apr 15 15:31:47 smsl-n100 volumio[1093]: info: sendMpdCommand status took 8 milliseconds Apr 15 15:31:47 smsl-n100 volumio[1093]: verbose: ControllerMpd::parseState Apr 15 15:31:47 smsl-n100 volumio[1093]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 15:31:47 smsl-n100 volumio[1093]: verbose: ControllerMpd::parseState Apr 15 15:31:47 smsl-n100 volumio[1093]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 15:31:47 smsl-n100 volumio[1093]: verbose: ControllerMpd::parseState Apr 15 15:31:47 smsl-n100 volumio[1093]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 15:31:47 smsl-n100 volumio[1093]: info: sendMpdCommand playlistinfo took 10 milliseconds Apr 15 15:31:47 smsl-n100 volumio[1093]: info: sendMpdCommand playlistinfo took 9 milliseconds Apr 15 15:31:47 smsl-n100 volumio[1093]: info: sendMpdCommand playlistinfo took 8 milliseconds Apr 15 15:31:47 smsl-n100 volumio[1093]: verbose: ControllerMpd::parseTrackInfo Apr 15 15:31:47 smsl-n100 volumio[1093]: verbose: ControllerMpd::parseTrackInfo Apr 15 15:31:47 smsl-n100 volumio[1093]: verbose: ControllerMpd::parseTrackInfo Apr 15 15:31:47 smsl-n100 volumio[1093]: info: ControllerMpd::pushState Apr 15 15:31:47 smsl-n100 volumio[1093]: info: CoreCommandRouter::servicePushState Apr 15 15:31:47 smsl-n100 volumio[1093]: info: CorePlayQueue::getTrack 0 Apr 15 15:31:47 smsl-n100 volumio[1093]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":107987,"duration":301,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"3029 Kbps","isStreaming":false,"title":"10473970","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/10473970","trackType":"qobuz"} Apr 15 15:31:47 smsl-n100 volumio[1093]: verbose: CURRENT POSITION 0 Apr 15 15:31:47 smsl-n100 volumio[1093]: info: CoreStateMachine::syncState stateService pause Apr 15 15:31:47 smsl-n100 volumio[1093]: info: CoreStateMachine::syncState currentStatus pause Apr 15 15:31:47 smsl-n100 volumio[1093]: info: CoreStateMachine::pushState Apr 15 15:31:47 smsl-n100 volumio[1093]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 15:31:47 smsl-n100 volumio[1093]: info: CoreCommandRouter::volumioPushState Apr 15 15:31:47 smsl-n100 volumio[1093]: info: MRS: Pushing multiroomSync output update for this device Apr 15 15:31:47 smsl-n100 volumio[1093]: info: MRS: Pushing multiroomSync output Apr 15 15:31:47 smsl-n100 volumio[1093]: info: CoreStateMachine::stPlaybackTimer Apr 15 15:31:47 smsl-n100 volumio[1093]: info: ControllerMpd::pushState Apr 15 15:31:47 smsl-n100 volumio[1093]: info: CoreCommandRouter::servicePushState Apr 15 15:31:47 smsl-n100 volumio[1093]: info: CorePlayQueue::getTrack 0 Apr 15 15:31:47 smsl-n100 volumio[1093]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":107987,"duration":301,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"3029 Kbps","isStreaming":false,"title":"10473970","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/10473970","trackType":"qobuz"} Apr 15 15:31:47 smsl-n100 volumio[1093]: verbose: CURRENT POSITION 0 Apr 15 15:31:47 smsl-n100 volumio[1093]: info: CoreStateMachine::syncState stateService pause Apr 15 15:31:47 smsl-n100 volumio[1093]: info: CoreStateMachine::syncState currentStatus pause Apr 15 15:31:47 smsl-n100 volumio[1093]: info: CoreStateMachine::pushState Apr 15 15:31:47 smsl-n100 volumio[1093]: info: CoreCommandRouter::volumioPushState Apr 15 15:31:47 smsl-n100 volumio[1093]: info: MRS: Pushing multiroomSync output update for this device Apr 15 15:31:47 smsl-n100 volumio[1093]: info: MRS: Pushing multiroomSync output Apr 15 15:31:47 smsl-n100 volumio[1093]: info: CoreStateMachine::stPlaybackTimer Apr 15 15:31:47 smsl-n100 volumio[1093]: info: ControllerMpd::pushState Apr 15 15:31:47 smsl-n100 volumio[1093]: info: CoreCommandRouter::servicePushState Apr 15 15:31:47 smsl-n100 volumio[1093]: info: CorePlayQueue::getTrack 0 Apr 15 15:31:47 smsl-n100 volumio[1093]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":107987,"duration":301,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"3029 Kbps","isStreaming":false,"title":"10473970","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/10473970","trackType":"qobuz"} Apr 15 15:31:47 smsl-n100 volumio[1093]: verbose: CURRENT POSITION 0 Apr 15 15:31:47 smsl-n100 volumio[1093]: info: CoreStateMachine::syncState stateService pause Apr 15 15:31:47 smsl-n100 volumio[1093]: info: CoreStateMachine::syncState currentStatus pause Apr 15 15:31:47 smsl-n100 volumio[1093]: info: CoreStateMachine::pushState Apr 15 15:31:47 smsl-n100 volumio[1093]: info: CoreCommandRouter::volumioPushState Apr 15 15:31:47 smsl-n100 volumio[1093]: info: MRS: Pushing multiroomSync output update for this device Apr 15 15:31:47 smsl-n100 volumio[1093]: info: MRS: Pushing multiroomSync output Apr 15 15:31:47 smsl-n100 volumio[1093]: info: CoreStateMachine::stPlaybackTimer Apr 15 15:31:47 smsl-n100 volumio[1093]: info: ------------------------------ 231ms Apr 15 15:31:47 smsl-n100 volumio[1093]: info: ------------------------------ 226ms Apr 15 15:31:47 smsl-n100 volumio[1093]: info: ------------------------------ 221ms Apr 15 15:31:47 smsl-n100 volumio[1093]: info: CoreStateMachine::pushState Apr 15 15:31:47 smsl-n100 volumio[1093]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 15:31:47 smsl-n100 volumio[1093]: info: CoreCommandRouter::volumioPushState Apr 15 15:31:47 smsl-n100 volumio[1093]: info: MRS: Pushing multiroomSync output update for this device Apr 15 15:31:47 smsl-n100 volumio[1093]: info: MRS: Pushing multiroomSync output Apr 15 15:31:47 smsl-n100 volumio[1093]: error: Serial API: Failed to decode command: 100, message: undefined Apr 15 15:31:47 smsl-n100 volumio[1093]: error: Serial API: Failed to decode command: MAXVOL, message: Apr 15 15:31:47 smsl-n100 volumio[1093]: error: Serial API: Failed to decode command: 100, message: undefined Apr 15 15:31:47 smsl-n100 volumio[1093]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Apr 15 15:31:47 smsl-n100 volumio[1093]: info: VolumeController::SetAlsaVolume0 Apr 15 15:31:47 smsl-n100 volumio[1093]: info: CoreStateMachine::pushState Apr 15 15:31:47 smsl-n100 volumio[1093]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 15:31:47 smsl-n100 volumio[1093]: info: CoreCommandRouter::volumioPushState Apr 15 15:31:47 smsl-n100 volumio[1093]: info: MRS: Pushing multiroomSync output update for this device Apr 15 15:31:47 smsl-n100 volumio[1093]: info: MRS: Pushing multiroomSync output Apr 15 15:31:49 smsl-n100 volumio[1093]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 15 15:31:49 smsl-n100 volumio[1093]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 15 15:31:49 smsl-n100 volumio[1093]: info: Discovery: Getting this device information Apr 15 15:31:49 smsl-n100 volumio[1093]: info: CoreCommandRouter::volumioGetState Apr 15 15:31:49 smsl-n100 volumio[1093]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 15 15:31:49 smsl-n100 volumio[1093]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 15 15:31:50 smsl-n100 volumio[1093]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 15 15:31:50 smsl-n100 volumio[1093]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 15 15:31:50 smsl-n100 volumio[1093]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 15 15:31:50 smsl-n100 volumio[1093]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 15 15:31:50 smsl-n100 volumio[1093]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 15 15:31:50 smsl-n100 volumio[1093]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 15 15:31:50 smsl-n100 volumio[1093]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 15 15:31:50 smsl-n100 volumio[1093]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 15 15:31:50 smsl-n100 volumio[1093]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 15 15:31:50 smsl-n100 volumio[1093]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 15 15:31:50 smsl-n100 volumio[1093]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 15 15:31:50 smsl-n100 volumio[1093]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 15 15:32:10 smsl-n100 volumio[1093]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 15 15:32:10 smsl-n100 volumio[1093]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 15 15:32:10 smsl-n100 volumio[1093]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Apr 15 15:32:10 smsl-n100 sudo[2655]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Apr 15 15:32:10 smsl-n100 sudo[2655]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 15:32:10 smsl-n100 sudo[2655]: pam_unix(sudo:session): session closed for user root Apr 15 15:32:10 smsl-n100 sudo[2661]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Apr 15 15:32:10 smsl-n100 sudo[2661]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 15:32:11 smsl-n100 sudo[2661]: pam_unix(sudo:session): session closed for user root Apr 15 15:32:11 smsl-n100 sudo[2669]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Apr 15 15:32:11 smsl-n100 sudo[2669]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 15:32:11 smsl-n100 sudo[2669]: pam_unix(sudo:session): session closed for user root Apr 15 15:32:11 smsl-n100 sudo[2675]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Apr 15 15:32:11 smsl-n100 sudo[2675]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 15:32:11 smsl-n100 sudo[2675]: pam_unix(sudo:session): session closed for user root Apr 15 15:32:11 smsl-n100 sudo[2680]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 15 15:32:11 smsl-n100 sudo[2680]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 15:32:11 smsl-n100 volumio[1093]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Apr 15 15:32:11 smsl-n100 sudo[2680]: pam_unix(sudo:session): session closed for user root Apr 15 15:32:11 smsl-n100 volumio[1093]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Apr 15 15:32:11 smsl-n100 sudo[2683]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 15 15:32:11 smsl-n100 sudo[2683]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 15:32:11 smsl-n100 sudo[2683]: pam_unix(sudo:session): session closed for user root Apr 15 15:32:11 smsl-n100 sudo[2687]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 15 15:32:11 smsl-n100 sudo[2687]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 15:32:15 smsl-n100 sudo[2687]: pam_unix(sudo:session): session closed for user root Apr 15 15:32:19 smsl-n100 volumio[1093]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 15 15:32:19 smsl-n100 volumio[1093]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 15 15:32:19 smsl-n100 volumio[1093]: info: Discovery: Getting this device information Apr 15 15:32:19 smsl-n100 volumio[1093]: info: CoreCommandRouter::volumioGetState Apr 15 15:32:19 smsl-n100 volumio[1093]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 15 15:32:31 smsl-n100 volumio[1093]: info: CALLMETHOD: system_controller network saveWiredNet [object Object] Apr 15 15:32:31 smsl-n100 volumio[1093]: info: CoreCommandRouter::executeOnPlugin: network , saveWiredNet Apr 15 15:32:31 smsl-n100 sudo[2726]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/network/interfaces Apr 15 15:32:31 smsl-n100 sudo[2726]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 15:32:31 smsl-n100 sudo[2726]: pam_unix(sudo:session): session closed for user root Apr 15 15:32:31 smsl-n100 sudo[2728]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/dhcpcd.conf Apr 15 15:32:31 smsl-n100 sudo[2728]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 15:32:31 smsl-n100 sudo[2728]: pam_unix(sudo:session): session closed for user root Apr 15 15:32:31 smsl-n100 volumio[1093]: info: Permissions for /etc/network/interfaces set Apr 15 15:32:31 smsl-n100 volumio[1093]: info: Restarting Wired Network Apr 15 15:32:31 smsl-n100 volumio[1093]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Apr 15 15:32:31 smsl-n100 volumio[1093]: info: Discovery: Restarting Advertising due to device name change Apr 15 15:32:31 smsl-n100 sudo[2731]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ip addr flush dev eth0 Apr 15 15:32:31 smsl-n100 sudo[2731]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 15:32:31 smsl-n100 avahi-daemon[560]: Withdrawing address record for 192.168.5.172 on eth0. Apr 15 15:32:31 smsl-n100 dhcpcd[954]: eth0: pid 2732 deleted IP address 192.168.5.172/24 Apr 15 15:32:31 smsl-n100 volumio[1093]: info: Discovery: A device disappeared from network Apr 15 15:32:31 smsl-n100 avahi-daemon[560]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.5.172. Apr 15 15:32:31 smsl-n100 dhcpcd[953]: eth0: pid 2732 deleted IP address 192.168.5.172/24 Apr 15 15:32:31 smsl-n100 dhcpcd[954]: eth0: deleting route to 192.168.5.0/24 Apr 15 15:32:31 smsl-n100 dhcpcd[953]: eth0: deleting route to 192.168.5.0/24 Apr 15 15:32:31 smsl-n100 avahi-daemon[560]: Interface eth0.IPv4 no longer relevant for mDNS. Apr 15 15:32:31 smsl-n100 dhcpcd[953]: eth0: deleting default route via 192.168.5.1 Apr 15 15:32:31 smsl-n100 dhcpcd[954]: eth0: deleting default route via 192.168.5.1 Apr 15 15:32:31 smsl-n100 sudo[2731]: pam_unix(sudo:session): session closed for user root Apr 15 15:32:31 smsl-n100 sudo[2735]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 down Apr 15 15:32:31 smsl-n100 sudo[2735]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 15:32:31 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: Link is Down Apr 15 15:32:31 smsl-n100 sudo[2735]: pam_unix(sudo:session): session closed for user root Apr 15 15:32:31 smsl-n100 dhcpcd[954]: eth0: rebinding lease of 192.168.5.172 Apr 15 15:32:31 smsl-n100 dhcpcd[953]: eth0: rebinding lease of 192.168.5.172 Apr 15 15:32:31 smsl-n100 dhcpcd[954]: eth0: if_sendraw: Network is down Apr 15 15:32:31 smsl-n100 dhcpcd[953]: eth0: if_sendraw: Network is down Apr 15 15:32:31 smsl-n100 dhcpcd[954]: dhcp_readpacket: eth0: Network is down Apr 15 15:32:31 smsl-n100 dhcpcd[953]: dhcp_readpacket: eth0: Network is down Apr 15 15:32:31 smsl-n100 sudo[2761]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 up Apr 15 15:32:31 smsl-n100 dhcpcd[954]: eth0: carrier lost Apr 15 15:32:31 smsl-n100 sudo[2761]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 15:32:31 smsl-n100 dhcpcd[953]: eth0: carrier lost Apr 15 15:32:31 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: PHY [stmmac-0:01] driver [Generic PHY] Apr 15 15:32:31 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: No Safety Features support found Apr 15 15:32:31 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: PTP not supported by HW Apr 15 15:32:31 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: configuring for phy/rgmii link mode Apr 15 15:32:31 smsl-n100 sudo[2761]: pam_unix(sudo:session): session closed for user root Apr 15 15:32:32 smsl-n100 ifplugd(eth0)[758]: Link beat lost. Apr 15 15:32:32 smsl-n100 volumio[1093]: info: Discovery: A device disappeared from network Apr 15 15:32:33 smsl-n100 ntpd[1082]: Deleting interface #3 eth0, 192.168.5.172#123, interface stats: received=236, sent=239, dropped=0, active_time=390 secs Apr 15 15:32:33 smsl-n100 ntpd[1082]: 46.17.88.210 local addr 192.168.5.172 -> Apr 15 15:32:33 smsl-n100 ntpd[1082]: 178.215.228.24 local addr 192.168.5.172 -> Apr 15 15:32:33 smsl-n100 ntpd[1082]: 85.199.214.100 local addr 192.168.5.172 -> Apr 15 15:32:33 smsl-n100 ntpd[1082]: 80.87.128.222 local addr 192.168.5.172 -> Apr 15 15:32:33 smsl-n100 ntpd[1082]: 193.57.144.50 local addr 192.168.5.172 -> Apr 15 15:32:33 smsl-n100 ntpd[1082]: 131.111.8.61 local addr 192.168.5.172 -> Apr 15 15:32:33 smsl-n100 ntpd[1082]: 46.101.52.249 local addr 192.168.5.172 -> Apr 15 15:32:33 smsl-n100 ntpd[1082]: 51.89.151.183 local addr 192.168.5.172 -> Apr 15 15:32:33 smsl-n100 ntpd[1082]: 176.58.115.34 local addr 192.168.5.172 -> Apr 15 15:32:33 smsl-n100 ntpd[1082]: 131.111.8.63 local addr 192.168.5.172 -> Apr 15 15:32:33 smsl-n100 ntpd[1082]: 185.53.45.4 local addr 192.168.5.172 -> Apr 15 15:32:33 smsl-n100 ntpd[1082]: 149.22.220.130 local addr 192.168.5.172 -> Apr 15 15:32:33 smsl-n100 ntpd[1082]: 129.250.35.251 local addr 192.168.5.172 -> Apr 15 15:32:33 smsl-n100 ntpd[1082]: 162.159.200.1 local addr 192.168.5.172 -> Apr 15 15:32:33 smsl-n100 ntpd[1082]: 178.18.123.148 local addr 192.168.5.172 -> Apr 15 15:32:33 smsl-n100 ntpd[1082]: 185.83.169.27 local addr 192.168.5.172 -> Apr 15 15:32:33 smsl-n100 ntpd[1082]: 131.111.8.60 local addr 192.168.5.172 -> Apr 15 15:32:33 smsl-n100 ntpd[1082]: 217.154.60.177 local addr 192.168.5.172 -> Apr 15 15:32:35 smsl-n100 volumio[1093]: info: Reporting MCU Network Status: 2 Apr 15 15:32:35 smsl-n100 volumio[1093]: info: Volumio Network Manager: Network status updated: 2 Apr 15 15:32:35 smsl-n100 volumio[1093]: info: CoreStateMachine::pushState Apr 15 15:32:35 smsl-n100 volumio[1093]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 15:32:35 smsl-n100 volumio[1093]: info: CoreCommandRouter::volumioPushState Apr 15 15:32:35 smsl-n100 volumio[1093]: info: MRS: Pushing multiroomSync output update for this device Apr 15 15:32:35 smsl-n100 volumio[1093]: info: MRS: Pushing multiroomSync output Apr 15 15:32:35 smsl-n100 volumio[1093]: error: Serial API: Failed to decode command: 100, message: undefined Apr 15 15:32:35 smsl-n100 volumio[1093]: error: Serial API: Failed to decode command: MAXVOL, message: Apr 15 15:32:35 smsl-n100 volumio[1093]: error: Serial API: Failed to decode command: 100, message: undefined Apr 15 15:32:35 smsl-n100 volumio[1093]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Apr 15 15:32:35 smsl-n100 volumio[1093]: error: Serial API: Failed to decode command: 2, message: undefined Apr 15 15:32:36 smsl-n100 volumio[1093]: info: Discovery: Started advertising with name: SMSL N100 Apr 15 15:32:36 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx Apr 15 15:32:36 smsl-n100 dhcpcd[954]: eth0: carrier acquired Apr 15 15:32:36 smsl-n100 dhcpcd[953]: eth0: carrier acquired Apr 15 15:32:36 smsl-n100 dhcpcd[954]: eth0: confile file changed, expiring leases Apr 15 15:32:36 smsl-n100 dhcpcd[953]: eth0: confile file changed, expiring leases Apr 15 15:32:36 smsl-n100 dhcpcd[953]: eth0: IAID 33:3f:26:26 Apr 15 15:32:36 smsl-n100 dhcpcd[954]: eth0: IAID 33:3f:26:26 Apr 15 15:32:37 smsl-n100 dhcpcd[953]: eth0: soliciting an IPv6 router Apr 15 15:32:37 smsl-n100 dhcpcd[953]: eth0: rebinding lease of 192.168.5.172 Apr 15 15:32:37 smsl-n100 ifplugd(eth0)[758]: Link beat detected. Apr 15 15:32:37 smsl-n100 dhcpcd[954]: eth0: soliciting an IPv6 router Apr 15 15:32:37 smsl-n100 dhcpcd[954]: eth0: rebinding lease of 192.168.5.172 Apr 15 15:32:37 smsl-n100 volumio[1093]: info: Discovery: adding 751ef502-b5e1-4a63-b9c3-9e0f3a955489 Apr 15 15:32:37 smsl-n100 volumio[1093]: info: Discovery: Found device SMSL N100 Apr 15 15:32:37 smsl-n100 volumio[1093]: info: CoreCommandRouter::volumioGetState Apr 15 15:32:37 smsl-n100 volumio[1093]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 15 15:32:37 smsl-n100 volumio[1093]: Error: The value NaN is not a number Apr 15 15:32:37 smsl-n100 volumio[1093]: at Config.forceToType (/volumio/node_modules/v-conf/index.js:322:20) Apr 15 15:32:37 smsl-n100 volumio[1093]: at Config.set (/volumio/node_modules/v-conf/index.js:153:25) Apr 15 15:32:37 smsl-n100 volumio[1093]: at ControllerVolumioDiscovery.updateMultiroomDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:323:25) Apr 15 15:32:37 smsl-n100 volumio[1093]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:273:10) Apr 15 15:32:37 smsl-n100 volumio[1093]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12) Apr 15 15:32:37 smsl-n100 volumio[1093]: at Browser.emit (events.js:400:28) Apr 15 15:32:37 smsl-n100 volumio[1093]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) Apr 15 15:32:37 smsl-n100 volumio[1093]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) Apr 15 15:32:37 smsl-n100 volumio[1093]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5) Apr 15 15:32:37 smsl-n100 volumio[1093]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21) Apr 15 15:32:37 smsl-n100 volumio[1093]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 15 15:32:38 smsl-n100 dhcpcd[953]: eth0: probing address 192.168.5.172/24 Apr 15 15:32:38 smsl-n100 dhcpcd[954]: eth0: probing address 192.168.5.172/24 Apr 15 15:32:38 smsl-n100 sudo[2868]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-15 15:31 Apr 15 15:32:38 smsl-n100 sudo[2868]: 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"