-- Logs begin at Tue 2025-04-15 16:16:10 CEST, end at Tue 2025-04-15 18:21:27 CEST. -- Apr 15 18:20:04 smsl-n100 volumio[1059]: info: Preload queue cleared Apr 15 18:20:04 smsl-n100 volumio[1059]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 15 18:20:04 smsl-n100 volumio[1059]: info: CoreStateMachine::ClearQueue Apr 15 18:20:04 smsl-n100 volumio[1059]: info: CoreStateMachine::stop Apr 15 18:20:04 smsl-n100 volumio[1059]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 15 18:20:04 smsl-n100 volumio[1059]: info: CoreStateMachine::stPlaybackTimer Apr 15 18:20:04 smsl-n100 volumio[1059]: info: CoreStateMachine::updateTrackBlock Apr 15 18:20:04 smsl-n100 volumio[1059]: info: CorePlayQueue::getTrackBlock Apr 15 18:20:04 smsl-n100 volumio[1059]: info: CoreStateMachine::pushState Apr 15 18:20:04 smsl-n100 volumio[1059]: info: CorePlayQueue::getTrack 0 Apr 15 18:20:04 smsl-n100 volumio[1059]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 18:20:04 smsl-n100 volumio[1059]: info: CoreCommandRouter::volumioPushState Apr 15 18:20:04 smsl-n100 volumio[1059]: info: CoreStateMachine::serviceStop Apr 15 18:20:04 smsl-n100 volumio[1059]: info: CorePlayQueue::getTrack 0 Apr 15 18:20:04 smsl-n100 volumio[1059]: info: CoreCommandRouter::serviceStop Apr 15 18:20:04 smsl-n100 volumio[1059]: info: [1744734004602] ControllerQobuz::stop Apr 15 18:20:04 smsl-n100 volumio[1059]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 15 18:20:04 smsl-n100 volumio[1059]: info: ControllerMpd::stop Apr 15 18:20:04 smsl-n100 volumio[1059]: verbose: ControllerMpd::sendMpdCommand stop Apr 15 18:20:04 smsl-n100 volumio[1059]: info: CorePlayQueue::clearPlayQueue Apr 15 18:20:04 smsl-n100 volumio[1059]: info: CorePlayQueue::saveQueue Apr 15 18:20:04 smsl-n100 volumio[1059]: info: CoreCommandRouter::volumioPushQueue Apr 15 18:20:04 smsl-n100 volumio[1059]: info: CoreStateMachine::addQueueItems Apr 15 18:20:04 smsl-n100 volumio[1059]: info: CorePlayQueue::addQueueItems Apr 15 18:20:04 smsl-n100 volumio[1059]: info: Preload queue cleared Apr 15 18:20:04 smsl-n100 volumio[1059]: info: Adding Item to queue: qobuz://album/5099749522428 Apr 15 18:20:04 smsl-n100 volumio[1059]: info: Exploding uri qobuz://album/5099749522428 in service qobuz Apr 15 18:20:04 smsl-n100 volumio[1059]: https://prod.vlmapi.io/v2/qobuz/explodeUri Apr 15 18:20:04 smsl-n100 volumio[1059]: STREAMING PROXY: Client dropped request, destroying Apr 15 18:20:04 smsl-n100 volumio[1059]: info: Apr 15 18:20:04 smsl-n100 volumio[1059]: ---------------------------- MPD announces state update: player Apr 15 18:20:04 smsl-n100 volumio[1059]: info: sendMpdCommand stop took 94 milliseconds Apr 15 18:20:04 smsl-n100 volumio[1059]: info: ControllerMpd::getState Apr 15 18:20:04 smsl-n100 volumio[1059]: verbose: ControllerMpd::sendMpdCommand status Apr 15 18:20:04 smsl-n100 volumio[1059]: info: Apr 15 18:20:04 smsl-n100 volumio[1059]: ---------------------------- MPD announces state update: player Apr 15 18:20:04 smsl-n100 volumio[1059]: info: ControllerMpd::getState Apr 15 18:20:04 smsl-n100 volumio[1059]: verbose: ControllerMpd::sendMpdCommand status Apr 15 18:20:04 smsl-n100 volumio[1059]: info: Apr 15 18:20:04 smsl-n100 volumio[1059]: ---------------------------- MPD announces state update: player Apr 15 18:20:04 smsl-n100 volumio[1059]: info: ControllerMpd::getState Apr 15 18:20:04 smsl-n100 volumio[1059]: verbose: ControllerMpd::sendMpdCommand status Apr 15 18:20:04 smsl-n100 volumio[1059]: info: sendMpdCommand status took 12 milliseconds Apr 15 18:20:04 smsl-n100 volumio[1059]: info: sendMpdCommand status took 8 milliseconds Apr 15 18:20:04 smsl-n100 volumio[1059]: info: sendMpdCommand status took 4 milliseconds Apr 15 18:20:04 smsl-n100 volumio[1059]: verbose: ControllerMpd::parseState Apr 15 18:20:04 smsl-n100 volumio[1059]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 18:20:04 smsl-n100 volumio[1059]: verbose: ControllerMpd::parseState Apr 15 18:20:04 smsl-n100 volumio[1059]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 18:20:04 smsl-n100 volumio[1059]: verbose: ControllerMpd::parseState Apr 15 18:20:04 smsl-n100 volumio[1059]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 18:20:04 smsl-n100 volumio[1059]: info: sendMpdCommand playlistinfo took 8 milliseconds Apr 15 18:20:04 smsl-n100 volumio[1059]: info: sendMpdCommand playlistinfo took 8 milliseconds Apr 15 18:20:04 smsl-n100 volumio[1059]: info: sendMpdCommand playlistinfo took 7 milliseconds Apr 15 18:20:04 smsl-n100 volumio[1059]: verbose: ControllerMpd::parseTrackInfo Apr 15 18:20:04 smsl-n100 volumio[1059]: verbose: ControllerMpd::parseTrackInfo Apr 15 18:20:04 smsl-n100 volumio[1059]: verbose: ControllerMpd::parseTrackInfo Apr 15 18:20:04 smsl-n100 volumio[1059]: info: ControllerMpd::pushState Apr 15 18:20:04 smsl-n100 volumio[1059]: info: CoreCommandRouter::servicePushState Apr 15 18:20:04 smsl-n100 volumio[1059]: info: CoreStateMachine::pushState Apr 15 18:20:04 smsl-n100 volumio[1059]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 18:20:04 smsl-n100 volumio[1059]: info: CoreCommandRouter::volumioPushState Apr 15 18:20:04 smsl-n100 volumio[1059]: info: CorePlayQueue::getTrack 0 Apr 15 18:20:04 smsl-n100 volumio[1059]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"215579808","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/215579808","trackType":"qobuz"} Apr 15 18:20:04 smsl-n100 volumio[1059]: verbose: CURRENT POSITION 0 Apr 15 18:20:04 smsl-n100 volumio[1059]: info: CoreStateMachine::syncState stateService stop Apr 15 18:20:04 smsl-n100 volumio[1059]: info: CoreStateMachine::syncState currentStatus stop Apr 15 18:20:04 smsl-n100 volumio[1059]: info: CoreStateMachine::pushState Apr 15 18:20:04 smsl-n100 volumio[1059]: info: CoreCommandRouter::volumioPushState Apr 15 18:20:04 smsl-n100 volumio[1059]: info: No code Apr 15 18:20:04 smsl-n100 volumio[1059]: info: CoreStateMachine::pushState Apr 15 18:20:04 smsl-n100 volumio[1059]: info: CoreCommandRouter::volumioPushState Apr 15 18:20:04 smsl-n100 volumio[1059]: info: ControllerMpd::pushState Apr 15 18:20:04 smsl-n100 volumio[1059]: info: CoreCommandRouter::servicePushState Apr 15 18:20:04 smsl-n100 volumio[1059]: info: CoreStateMachine::pushState Apr 15 18:20:04 smsl-n100 volumio[1059]: info: CoreCommandRouter::volumioPushState Apr 15 18:20:04 smsl-n100 volumio[1059]: info: CorePlayQueue::getTrack 0 Apr 15 18:20:04 smsl-n100 volumio[1059]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"215579808","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/215579808","trackType":"qobuz"} Apr 15 18:20:04 smsl-n100 volumio[1059]: verbose: CURRENT POSITION 0 Apr 15 18:20:04 smsl-n100 volumio[1059]: info: CoreStateMachine::syncState stateService stop Apr 15 18:20:04 smsl-n100 volumio[1059]: info: CoreStateMachine::syncState currentStatus stop Apr 15 18:20:04 smsl-n100 volumio[1059]: info: CoreStateMachine::pushState Apr 15 18:20:04 smsl-n100 volumio[1059]: info: CoreCommandRouter::volumioPushState Apr 15 18:20:04 smsl-n100 volumio[1059]: info: No code Apr 15 18:20:04 smsl-n100 volumio[1059]: info: CoreStateMachine::pushState Apr 15 18:20:04 smsl-n100 volumio[1059]: info: CoreCommandRouter::volumioPushState Apr 15 18:20:04 smsl-n100 volumio[1059]: info: ControllerMpd::pushState Apr 15 18:20:04 smsl-n100 volumio[1059]: info: CoreCommandRouter::servicePushState Apr 15 18:20:04 smsl-n100 volumio[1059]: info: CoreStateMachine::pushState Apr 15 18:20:04 smsl-n100 volumio[1059]: info: CoreCommandRouter::volumioPushState Apr 15 18:20:04 smsl-n100 volumio[1059]: info: CorePlayQueue::getTrack 0 Apr 15 18:20:04 smsl-n100 volumio[1059]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"215579808","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/215579808","trackType":"qobuz"} Apr 15 18:20:04 smsl-n100 volumio[1059]: verbose: CURRENT POSITION 0 Apr 15 18:20:04 smsl-n100 volumio[1059]: info: CoreStateMachine::syncState stateService stop Apr 15 18:20:04 smsl-n100 volumio[1059]: info: CoreStateMachine::syncState currentStatus stop Apr 15 18:20:04 smsl-n100 volumio[1059]: info: CoreStateMachine::pushState Apr 15 18:20:04 smsl-n100 volumio[1059]: info: CoreCommandRouter::volumioPushState Apr 15 18:20:04 smsl-n100 volumio[1059]: info: No code Apr 15 18:20:04 smsl-n100 volumio[1059]: info: CoreStateMachine::pushState Apr 15 18:20:04 smsl-n100 volumio[1059]: info: CoreCommandRouter::volumioPushState Apr 15 18:20:04 smsl-n100 volumio[1059]: info: ------------------------------ 282ms Apr 15 18:20:04 smsl-n100 volumio[1059]: info: ------------------------------ 279ms Apr 15 18:20:04 smsl-n100 volumio[1059]: info: ------------------------------ 277ms Apr 15 18:20:05 smsl-n100 volumio[1059]: info: CoreStateMachine::pushState Apr 15 18:20:05 smsl-n100 volumio[1059]: info: CoreCommandRouter::volumioPushState Apr 15 18:20:05 smsl-n100 volumio[1059]: error: Serial API: Failed to decode command: 100, message: undefined Apr 15 18:20:05 smsl-n100 volumio[1059]: error: Serial API: Failed to decode command: MAXVOL, message: Apr 15 18:20:05 smsl-n100 volumio[1059]: error: Serial API: Failed to decode command: 100, message: undefined Apr 15 18:20:05 smsl-n100 volumio[1059]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Apr 15 18:20:05 smsl-n100 volumio[1059]: info: CoreStateMachine::pushState Apr 15 18:20:05 smsl-n100 volumio[1059]: info: CoreCommandRouter::volumioPushState Apr 15 18:20:05 smsl-n100 volumio[1059]: error: Serial API: Failed to decode command: 100, message: undefined Apr 15 18:20:05 smsl-n100 volumio[1059]: error: Serial API: Failed to decode command: MAXVOL, message: Apr 15 18:20:05 smsl-n100 volumio[1059]: error: Serial API: Failed to decode command: 100, message: undefined Apr 15 18:20:05 smsl-n100 volumio[1059]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Apr 15 18:20:05 smsl-n100 volumio[1059]: info: explodeUri took 914 milliseconds Apr 15 18:20:05 smsl-n100 volumio[1059]: info: CoreCommandRouter::volumioPushQueue Apr 15 18:20:05 smsl-n100 volumio[1059]: info: CorePlayQueue::saveQueue Apr 15 18:20:05 smsl-n100 volumio[1059]: info: CoreStateMachine::updateTrackBlock Apr 15 18:20:05 smsl-n100 volumio[1059]: info: CorePlayQueue::getTrackBlock Apr 15 18:20:05 smsl-n100 volumio[1059]: info: CoreCommandRouter::volumioPlay Apr 15 18:20:05 smsl-n100 volumio[1059]: info: CoreStateMachine::play index 0 Apr 15 18:20:05 smsl-n100 volumio[1059]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 15 18:20:05 smsl-n100 volumio[1059]: info: CoreStateMachine::stop Apr 15 18:20:05 smsl-n100 volumio[1059]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 15 18:20:05 smsl-n100 volumio[1059]: info: CoreStateMachine::play index undefined Apr 15 18:20:05 smsl-n100 volumio[1059]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 15 18:20:05 smsl-n100 volumio[1059]: info: CorePlayQueue::getTrack 0 Apr 15 18:20:05 smsl-n100 volumio[1059]: info: CoreStateMachine::startPlaybackTimer Apr 15 18:20:05 smsl-n100 volumio[1059]: info: CorePlayQueue::getTrack 0 Apr 15 18:20:05 smsl-n100 volumio[1059]: info: [1744734005567] ControllerQobuz::clearAddPlayTrack Apr 15 18:20:05 smsl-n100 volumio[1059]: verbose: ControllerMpd::sendMpdCommand stop Apr 15 18:20:05 smsl-n100 volumio[1059]: info: sendMpdCommand stop took 14 milliseconds Apr 15 18:20:05 smsl-n100 volumio[1059]: verbose: ControllerMpd::sendMpdCommand clear Apr 15 18:20:05 smsl-n100 volumio[1059]: info: Apr 15 18:20:05 smsl-n100 volumio[1059]: ---------------------------- MPD announces system playlist update Apr 15 18:20:05 smsl-n100 volumio[1059]: info: Ignoring MPD Status Update Apr 15 18:20:05 smsl-n100 volumio[1059]: info: sendMpdCommand clear took 5 milliseconds Apr 15 18:20:05 smsl-n100 volumio[1059]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/13176083" Apr 15 18:20:05 smsl-n100 volumio[1059]: info: Apr 15 18:20:05 smsl-n100 volumio[1059]: ---------------------------- MPD announces system playlist update Apr 15 18:20:05 smsl-n100 volumio[1059]: info: Ignoring MPD Status Update Apr 15 18:20:05 smsl-n100 volumio[1059]: STREAMING PROXY: Handling url /?data=qobuz://song/13176083 Apr 15 18:20:05 smsl-n100 volumio[1059]: info: Apr 15 18:20:05 smsl-n100 volumio[1059]: ---------------------------- MPD announces system playlist update Apr 15 18:20:05 smsl-n100 volumio[1059]: info: Ignoring MPD Status Update Apr 15 18:20:05 smsl-n100 volumio[1059]: error: updateQueue error: null Apr 15 18:20:05 smsl-n100 volumio[1059]: info: ------------------------------ 20ms Apr 15 18:20:05 smsl-n100 volumio[1059]: info: Executing endpoint getStreamUrlqobuz Apr 15 18:20:05 smsl-n100 volumio[1059]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Apr 15 18:20:05 smsl-n100 volumio[1059]: info: getStreamUrl took 364 milliseconds Apr 15 18:20:05 smsl-n100 volumio[1059]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=233795&eid=13176083&fmt=27&profile=raw&app_id=539451548&cid=1457318&etsp=1744737605&hmac=E3NAYpJuKbp_vn6VxKpdi6zjaX4 Apr 15 18:20:06 smsl-n100 volumio[1059]: STREAMING PROXY: Response: 200, length: 328084816 Apr 15 18:20:06 smsl-n100 volumio[1059]: STREAMING PROXY: Client dropped request, destroying Apr 15 18:20:06 smsl-n100 volumio[1059]: error: updateQueue error: null Apr 15 18:20:06 smsl-n100 volumio[1059]: error: updateQueue error: null Apr 15 18:20:06 smsl-n100 volumio[1059]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/13176083" Apr 15 18:20:06 smsl-n100 volumio[1059]: info: ------------------------------ 514ms Apr 15 18:20:06 smsl-n100 volumio[1059]: info: ------------------------------ 511ms Apr 15 18:20:06 smsl-n100 volumio[1059]: info: Apr 15 18:20:06 smsl-n100 volumio[1059]: ---------------------------- MPD announces system playlist update Apr 15 18:20:06 smsl-n100 volumio[1059]: info: Ignoring MPD Status Update Apr 15 18:20:06 smsl-n100 volumio[1059]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/13176083" took 6 milliseconds Apr 15 18:20:06 smsl-n100 volumio[1059]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 15 18:20:06 smsl-n100 volumio[1059]: verbose: ControllerMpd::sendMpdCommand play Apr 15 18:20:06 smsl-n100 volumio[1059]: info: Apr 15 18:20:06 smsl-n100 volumio[1059]: ---------------------------- MPD announces system playlist update Apr 15 18:20:06 smsl-n100 volumio[1059]: info: Ignoring MPD Status Update Apr 15 18:20:06 smsl-n100 volumio[1059]: STREAMING PROXY: Handling url /?data=qobuz://song/13176083 Apr 15 18:20:06 smsl-n100 volumio[1059]: info: Apr 15 18:20:06 smsl-n100 volumio[1059]: ---------------------------- MPD announces system playlist update Apr 15 18:20:06 smsl-n100 volumio[1059]: info: Ignoring MPD Status Update Apr 15 18:20:06 smsl-n100 volumio[1059]: info: ------------------------------ 16ms Apr 15 18:20:06 smsl-n100 volumio[1059]: info: sendMpdCommand play took 12 milliseconds Apr 15 18:20:06 smsl-n100 volumio[1059]: info: ------------------------------ 11ms Apr 15 18:20:06 smsl-n100 volumio[1059]: info: ------------------------------ 8ms Apr 15 18:20:06 smsl-n100 volumio[1059]: info: Executing endpoint getStreamUrlqobuz Apr 15 18:20:06 smsl-n100 volumio[1059]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Apr 15 18:20:06 smsl-n100 volumio[1059]: info: getStreamUrl took 369 milliseconds Apr 15 18:20:06 smsl-n100 volumio[1059]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=233795&eid=13176083&fmt=27&profile=raw&app_id=539451548&cid=1457318&etsp=1744737606&hmac=TbHtOhmP4Qk_0PxrOt9sr3t-yRI Apr 15 18:20:06 smsl-n100 volumio[1059]: STREAMING PROXY: Response: 200, length: 328084816 Apr 15 18:20:06 smsl-n100 volumio[1059]: info: Apr 15 18:20:06 smsl-n100 volumio[1059]: ---------------------------- MPD announces state update: player Apr 15 18:20:06 smsl-n100 volumio[1059]: info: ControllerMpd::getState Apr 15 18:20:06 smsl-n100 volumio[1059]: verbose: ControllerMpd::sendMpdCommand status Apr 15 18:20:06 smsl-n100 volumio[1059]: info: Apr 15 18:20:06 smsl-n100 volumio[1059]: ---------------------------- MPD announces state update: player Apr 15 18:20:06 smsl-n100 volumio[1059]: info: ControllerMpd::getState Apr 15 18:20:06 smsl-n100 volumio[1059]: verbose: ControllerMpd::sendMpdCommand status Apr 15 18:20:06 smsl-n100 volumio[1059]: info: Apr 15 18:20:06 smsl-n100 volumio[1059]: ---------------------------- MPD announces state update: player Apr 15 18:20:06 smsl-n100 volumio[1059]: info: ControllerMpd::getState Apr 15 18:20:06 smsl-n100 volumio[1059]: verbose: ControllerMpd::sendMpdCommand status Apr 15 18:20:06 smsl-n100 volumio[1059]: info: Apr 15 18:20:06 smsl-n100 volumio[1059]: ---------------------------- MPD announces state update: player Apr 15 18:20:06 smsl-n100 volumio[1059]: info: ControllerMpd::getState Apr 15 18:20:06 smsl-n100 volumio[1059]: verbose: ControllerMpd::sendMpdCommand status Apr 15 18:20:06 smsl-n100 volumio[1059]: info: sendMpdCommand status took 23 milliseconds Apr 15 18:20:06 smsl-n100 volumio[1059]: info: sendMpdCommand status took 18 milliseconds Apr 15 18:20:06 smsl-n100 volumio[1059]: info: sendMpdCommand status took 11 milliseconds Apr 15 18:20:06 smsl-n100 volumio[1059]: verbose: ControllerMpd::parseState Apr 15 18:20:06 smsl-n100 volumio[1059]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 18:20:06 smsl-n100 volumio[1059]: verbose: ControllerMpd::parseState Apr 15 18:20:06 smsl-n100 volumio[1059]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 18:20:06 smsl-n100 volumio[1059]: verbose: ControllerMpd::parseState Apr 15 18:20:06 smsl-n100 volumio[1059]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 18:20:06 smsl-n100 volumio[1059]: info: Apr 15 18:20:06 smsl-n100 volumio[1059]: ---------------------------- MPD announces state update: player Apr 15 18:20:06 smsl-n100 volumio[1059]: info: ControllerMpd::getState Apr 15 18:20:06 smsl-n100 volumio[1059]: verbose: ControllerMpd::sendMpdCommand status Apr 15 18:20:06 smsl-n100 volumio[1059]: info: Apr 15 18:20:06 smsl-n100 volumio[1059]: ---------------------------- MPD announces state update: player Apr 15 18:20:06 smsl-n100 volumio[1059]: info: ControllerMpd::getState Apr 15 18:20:06 smsl-n100 volumio[1059]: verbose: ControllerMpd::sendMpdCommand status Apr 15 18:20:06 smsl-n100 volumio[1059]: info: sendMpdCommand status took 51 milliseconds Apr 15 18:20:06 smsl-n100 volumio[1059]: info: sendMpdCommand playlistinfo took 43 milliseconds Apr 15 18:20:06 smsl-n100 volumio[1059]: info: sendMpdCommand playlistinfo took 42 milliseconds Apr 15 18:20:06 smsl-n100 volumio[1059]: info: sendMpdCommand playlistinfo took 41 milliseconds Apr 15 18:20:06 smsl-n100 volumio[1059]: info: sendMpdCommand status took 32 milliseconds Apr 15 18:20:06 smsl-n100 volumio[1059]: info: sendMpdCommand status took 26 milliseconds Apr 15 18:20:06 smsl-n100 volumio[1059]: verbose: ControllerMpd::parseState Apr 15 18:20:06 smsl-n100 volumio[1059]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 18:20:06 smsl-n100 volumio[1059]: verbose: ControllerMpd::parseTrackInfo Apr 15 18:20:06 smsl-n100 volumio[1059]: verbose: ControllerMpd::parseState Apr 15 18:20:06 smsl-n100 volumio[1059]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 18:20:06 smsl-n100 volumio[1059]: verbose: ControllerMpd::parseState Apr 15 18:20:06 smsl-n100 volumio[1059]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 18:20:06 smsl-n100 volumio[1059]: info: ControllerMpd::pushState Apr 15 18:20:06 smsl-n100 volumio[1059]: info: CoreCommandRouter::servicePushState Apr 15 18:20:06 smsl-n100 volumio[1059]: info: CorePlayQueue::getTrack 0 Apr 15 18:20:06 smsl-n100 volumio[1059]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":548,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"13176083","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/13176083","trackType":"qobuz"} Apr 15 18:20:06 smsl-n100 volumio[1059]: verbose: CURRENT POSITION 0 Apr 15 18:20:06 smsl-n100 volumio[1059]: info: CoreStateMachine::syncState stateService play Apr 15 18:20:06 smsl-n100 volumio[1059]: info: CoreStateMachine::syncState currentStatus stop Apr 15 18:20:06 smsl-n100 volumio[1059]: info: ControllerMpd::pushState Apr 15 18:20:06 smsl-n100 volumio[1059]: info: CoreCommandRouter::servicePushState Apr 15 18:20:06 smsl-n100 volumio[1059]: info: CorePlayQueue::getTrack 0 Apr 15 18:20:06 smsl-n100 volumio[1059]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":548,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"13176083","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/13176083","trackType":"qobuz"} Apr 15 18:20:06 smsl-n100 volumio[1059]: verbose: CURRENT POSITION 0 Apr 15 18:20:06 smsl-n100 volumio[1059]: info: CoreStateMachine::syncState stateService play Apr 15 18:20:06 smsl-n100 volumio[1059]: info: CoreStateMachine::syncState currentStatus play Apr 15 18:20:06 smsl-n100 volumio[1059]: info: Received an update from plugin. extracting info from payload Apr 15 18:20:06 smsl-n100 volumio[1059]: info: CoreStateMachine::pushState Apr 15 18:20:06 smsl-n100 volumio[1059]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 18:20:06 smsl-n100 volumio[1059]: info: CoreCommandRouter::volumioPushState Apr 15 18:20:06 smsl-n100 volumio[1059]: info: CoreStateMachine::pushState Apr 15 18:20:06 smsl-n100 volumio[1059]: info: CoreCommandRouter::volumioPushState Apr 15 18:20:06 smsl-n100 volumio[1059]: info: ControllerMpd::pushState Apr 15 18:20:06 smsl-n100 volumio[1059]: info: CoreCommandRouter::servicePushState Apr 15 18:20:06 smsl-n100 volumio[1059]: info: CorePlayQueue::getTrack 0 Apr 15 18:20:06 smsl-n100 volumio[1059]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":548,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"13176083","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/13176083","trackType":"qobuz"} Apr 15 18:20:06 smsl-n100 volumio[1059]: verbose: CURRENT POSITION 0 Apr 15 18:20:06 smsl-n100 volumio[1059]: info: CoreStateMachine::syncState stateService play Apr 15 18:20:06 smsl-n100 volumio[1059]: info: CoreStateMachine::syncState currentStatus play Apr 15 18:20:06 smsl-n100 volumio[1059]: info: Received an update from plugin. extracting info from payload Apr 15 18:20:06 smsl-n100 volumio[1059]: info: CoreStateMachine::pushState Apr 15 18:20:06 smsl-n100 volumio[1059]: info: CoreCommandRouter::volumioPushState Apr 15 18:20:06 smsl-n100 volumio[1059]: info: CoreStateMachine::pushState Apr 15 18:20:06 smsl-n100 volumio[1059]: info: CoreCommandRouter::volumioPushState Apr 15 18:20:06 smsl-n100 volumio[1059]: info: ------------------------------ 203ms Apr 15 18:20:06 smsl-n100 volumio[1059]: info: ------------------------------ 318ms Apr 15 18:20:06 smsl-n100 volumio[1059]: info: ------------------------------ 314ms Apr 15 18:20:06 smsl-n100 volumio[1059]: info: sendMpdCommand playlistinfo took 241 milliseconds Apr 15 18:20:06 smsl-n100 volumio[1059]: info: sendMpdCommand playlistinfo took 234 milliseconds Apr 15 18:20:06 smsl-n100 volumio[1059]: info: sendMpdCommand playlistinfo took 232 milliseconds Apr 15 18:20:06 smsl-n100 volumio[1059]: verbose: ControllerMpd::parseTrackInfo Apr 15 18:20:06 smsl-n100 volumio[1059]: verbose: ControllerMpd::parseTrackInfo Apr 15 18:20:06 smsl-n100 volumio[1059]: verbose: ControllerMpd::parseTrackInfo Apr 15 18:20:06 smsl-n100 volumio[1059]: info: ControllerMpd::pushState Apr 15 18:20:06 smsl-n100 volumio[1059]: info: CoreCommandRouter::servicePushState Apr 15 18:20:06 smsl-n100 volumio[1059]: info: CorePlayQueue::getTrack 0 Apr 15 18:20:06 smsl-n100 volumio[1059]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":548,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"13176083","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/13176083","trackType":"qobuz"} Apr 15 18:20:06 smsl-n100 volumio[1059]: verbose: CURRENT POSITION 0 Apr 15 18:20:06 smsl-n100 volumio[1059]: info: CoreStateMachine::syncState stateService play Apr 15 18:20:06 smsl-n100 volumio[1059]: info: CoreStateMachine::syncState currentStatus play Apr 15 18:20:06 smsl-n100 volumio[1059]: info: Received an update from plugin. extracting info from payload Apr 15 18:20:06 smsl-n100 volumio[1059]: info: CoreStateMachine::pushState Apr 15 18:20:06 smsl-n100 volumio[1059]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 18:20:06 smsl-n100 volumio[1059]: info: CoreCommandRouter::volumioPushState Apr 15 18:20:07 smsl-n100 volumio[1059]: info: CoreStateMachine::pushState Apr 15 18:20:07 smsl-n100 volumio[1059]: info: CoreCommandRouter::volumioPushState Apr 15 18:20:07 smsl-n100 volumio[1059]: info: ControllerMpd::pushState Apr 15 18:20:07 smsl-n100 volumio[1059]: info: CoreCommandRouter::servicePushState Apr 15 18:20:07 smsl-n100 volumio[1059]: info: CorePlayQueue::getTrack 0 Apr 15 18:20:07 smsl-n100 volumio[1059]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":548,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"13176083","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/13176083","trackType":"qobuz"} Apr 15 18:20:07 smsl-n100 volumio[1059]: verbose: CURRENT POSITION 0 Apr 15 18:20:07 smsl-n100 volumio[1059]: info: CoreStateMachine::syncState stateService play Apr 15 18:20:07 smsl-n100 volumio[1059]: info: CoreStateMachine::syncState currentStatus play Apr 15 18:20:07 smsl-n100 volumio[1059]: info: Received an update from plugin. extracting info from payload Apr 15 18:20:07 smsl-n100 volumio[1059]: info: CoreStateMachine::pushState Apr 15 18:20:07 smsl-n100 volumio[1059]: info: CoreCommandRouter::volumioPushState Apr 15 18:20:07 smsl-n100 volumio[1059]: info: CoreStateMachine::pushState Apr 15 18:20:07 smsl-n100 volumio[1059]: info: CoreCommandRouter::volumioPushState Apr 15 18:20:07 smsl-n100 volumio[1059]: info: ControllerMpd::pushState Apr 15 18:20:07 smsl-n100 volumio[1059]: info: CoreCommandRouter::servicePushState Apr 15 18:20:07 smsl-n100 volumio[1059]: info: CorePlayQueue::getTrack 0 Apr 15 18:20:07 smsl-n100 volumio[1059]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":548,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"13176083","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/13176083","trackType":"qobuz"} Apr 15 18:20:07 smsl-n100 volumio[1059]: verbose: CURRENT POSITION 0 Apr 15 18:20:07 smsl-n100 volumio[1059]: info: CoreStateMachine::syncState stateService play Apr 15 18:20:07 smsl-n100 volumio[1059]: info: CoreStateMachine::syncState currentStatus play Apr 15 18:20:07 smsl-n100 volumio[1059]: info: Received an update from plugin. extracting info from payload Apr 15 18:20:07 smsl-n100 volumio[1059]: info: CoreStateMachine::pushState Apr 15 18:20:07 smsl-n100 volumio[1059]: info: CoreCommandRouter::volumioPushState Apr 15 18:20:07 smsl-n100 volumio[1059]: info: CoreStateMachine::pushState Apr 15 18:20:07 smsl-n100 volumio[1059]: info: CoreCommandRouter::volumioPushState Apr 15 18:20:07 smsl-n100 volumio[1059]: info: ------------------------------ 567ms Apr 15 18:20:07 smsl-n100 volumio[1059]: info: ------------------------------ 544ms Apr 15 18:20:07 smsl-n100 volumio[1059]: info: ------------------------------ 539ms Apr 15 18:20:22 smsl-n100 volumio[1059]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 15 18:20:22 smsl-n100 volumio[1059]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 15 18:20:22 smsl-n100 volumio[1059]: info: Discovery: Getting this device information Apr 15 18:20:22 smsl-n100 volumio[1059]: info: CoreCommandRouter::volumioGetState Apr 15 18:20:22 smsl-n100 volumio[1059]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 15 18:21:05 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: Link is Down Apr 15 18:21:05 smsl-n100 dhcpcd[945]: eth0: carrier lost Apr 15 18:21:05 smsl-n100 dhcpcd[613]: eth0: carrier lost Apr 15 18:21:05 smsl-n100 avahi-daemon[526]: Withdrawing address record for 192.168.160.11 on eth0. Apr 15 18:21:05 smsl-n100 avahi-daemon[526]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.160.11. Apr 15 18:21:05 smsl-n100 avahi-daemon[526]: Interface eth0.IPv4 no longer relevant for mDNS. Apr 15 18:21:05 smsl-n100 volumio[1059]: info: Discovery: A device disappeared from network Apr 15 18:21:05 smsl-n100 dhcpcd[613]: eth0: deleting route to 192.168.160.0/24 Apr 15 18:21:05 smsl-n100 dhcpcd[613]: eth0: deleting default route via 192.168.160.1 Apr 15 18:21:05 smsl-n100 dhcpcd[945]: eth0: deleting route to 192.168.160.0/24 Apr 15 18:21:05 smsl-n100 dhcpcd[945]: eth0: deleting default route via 192.168.160.1 Apr 15 18:21:06 smsl-n100 ifplugd(eth0)[756]: Link beat lost. Apr 15 18:21:07 smsl-n100 ntpd[944]: Deleting interface #3 eth0, 192.168.160.11#123, interface stats: received=246, sent=248, dropped=0, active_time=398 secs Apr 15 18:21:07 smsl-n100 ntpd[944]: 217.160.19.219 local addr 192.168.160.11 -> Apr 15 18:21:07 smsl-n100 ntpd[944]: 85.215.93.134 local addr 192.168.160.11 -> Apr 15 18:21:07 smsl-n100 ntpd[944]: 185.252.140.126 local addr 192.168.160.11 -> Apr 15 18:21:07 smsl-n100 ntpd[944]: 85.220.190.246 local addr 192.168.160.11 -> Apr 15 18:21:07 smsl-n100 ntpd[944]: 157.90.24.29 local addr 192.168.160.11 -> Apr 15 18:21:07 smsl-n100 ntpd[944]: 185.252.140.125 local addr 192.168.160.11 -> Apr 15 18:21:07 smsl-n100 ntpd[944]: 94.130.184.193 local addr 192.168.160.11 -> Apr 15 18:21:07 smsl-n100 ntpd[944]: 162.159.200.123 local addr 192.168.160.11 -> Apr 15 18:21:07 smsl-n100 ntpd[944]: 194.59.205.229 local addr 192.168.160.11 -> Apr 15 18:21:07 smsl-n100 ntpd[944]: 85.214.83.151 local addr 192.168.160.11 -> Apr 15 18:21:07 smsl-n100 ntpd[944]: 51.75.67.47 local addr 192.168.160.11 -> Apr 15 18:21:07 smsl-n100 ntpd[944]: 176.9.44.212 local addr 192.168.160.11 -> Apr 15 18:21:07 smsl-n100 ntpd[944]: 168.119.211.223 local addr 192.168.160.11 -> Apr 15 18:21:07 smsl-n100 ntpd[944]: 88.198.53.80 local addr 192.168.160.11 -> Apr 15 18:21:07 smsl-n100 ntpd[944]: 162.159.200.1 local addr 192.168.160.11 -> Apr 15 18:21:07 smsl-n100 ntpd[944]: 185.248.189.10 local addr 192.168.160.11 -> Apr 15 18:21:07 smsl-n100 ntpd[944]: 134.255.247.126 local addr 192.168.160.11 -> Apr 15 18:21:07 smsl-n100 ntpd[944]: 144.91.126.59 local addr 192.168.160.11 -> Apr 15 18:21:11 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx Apr 15 18:21:11 smsl-n100 dhcpcd[945]: eth0: carrier acquired Apr 15 18:21:11 smsl-n100 dhcpcd[613]: eth0: carrier acquired Apr 15 18:21:11 smsl-n100 dhcpcd[945]: eth0: IAID 65:1f:a5:c8 Apr 15 18:21:11 smsl-n100 dhcpcd[613]: eth0: IAID 65:1f:a5:c8 Apr 15 18:21:11 smsl-n100 dhcpcd[613]: eth0: soliciting an IPv6 router Apr 15 18:21:11 smsl-n100 ifplugd(eth0)[756]: Link beat detected. Apr 15 18:21:11 smsl-n100 dhcpcd[613]: eth0: rebinding lease of 192.168.160.11 Apr 15 18:21:11 smsl-n100 dhcpcd[945]: eth0: soliciting an IPv6 router Apr 15 18:21:11 smsl-n100 dhcpcd[945]: eth0: rebinding lease of 192.168.160.11 Apr 15 18:21:14 smsl-n100 volumio[1059]: info: Reporting MCU Network Status: 0 Apr 15 18:21:14 smsl-n100 volumio[1059]: info: Volumio Network Manager: Network status updated: 0 Apr 15 18:21:14 smsl-n100 volumio[1059]: info: CoreStateMachine::pushState Apr 15 18:21:14 smsl-n100 volumio[1059]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 18:21:14 smsl-n100 volumio[1059]: info: CoreCommandRouter::volumioPushState Apr 15 18:21:14 smsl-n100 volumio[1059]: error: Serial API: Failed to decode command: 100, message: undefined Apr 15 18:21:14 smsl-n100 volumio[1059]: error: Serial API: Failed to decode command: MAXVOL, message: Apr 15 18:21:14 smsl-n100 volumio[1059]: error: Serial API: Failed to decode command: 100, message: undefined Apr 15 18:21:14 smsl-n100 volumio[1059]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Apr 15 18:21:14 smsl-n100 volumio[1059]: error: Serial API: Failed to decode command: 0, message: undefined Apr 15 18:21:16 smsl-n100 dhcpcd[613]: eth0: probing for an IPv4LL address Apr 15 18:21:16 smsl-n100 dhcpcd[613]: eth0: DHCP lease expired Apr 15 18:21:16 smsl-n100 dhcpcd[613]: eth0: soliciting a DHCP lease Apr 15 18:21:16 smsl-n100 dhcpcd[945]: eth0: probing for an IPv4LL address Apr 15 18:21:16 smsl-n100 dhcpcd[945]: eth0: DHCP lease expired Apr 15 18:21:16 smsl-n100 dhcpcd[945]: eth0: soliciting a DHCP lease Apr 15 18:21:17 smsl-n100 dhcpcd[613]: eth0: offered 192.168.160.11 from 192.168.160.1 Apr 15 18:21:17 smsl-n100 dhcpcd[613]: eth0: probing address 192.168.160.11/24 Apr 15 18:21:19 smsl-n100 dhcpcd[945]: eth0: offered 192.168.160.11 from 192.168.160.1 Apr 15 18:21:19 smsl-n100 dhcpcd[945]: eth0: probing address 192.168.160.11/24 Apr 15 18:21:20 smsl-n100 dhcpcd[613]: eth0: using IPv4LL address 169.254.83.202 Apr 15 18:21:20 smsl-n100 dhcpcd[613]: eth0: adding route to 169.254.0.0/16 Apr 15 18:21:20 smsl-n100 dhcpcd[613]: eth0: adding default route Apr 15 18:21:20 smsl-n100 avahi-daemon[526]: Joining mDNS multicast group on interface eth0.IPv4 with address 169.254.83.202. Apr 15 18:21:20 smsl-n100 avahi-daemon[526]: New relevant interface eth0.IPv4 for mDNS. Apr 15 18:21:20 smsl-n100 avahi-daemon[526]: Registering new address record for 169.254.83.202 on eth0.IPv4. Apr 15 18:21:21 smsl-n100 dhcpcd[945]: eth0: using IPv4LL address 169.254.83.202 Apr 15 18:21:21 smsl-n100 dhcpcd[945]: eth0: adding route to 169.254.0.0/16 Apr 15 18:21:21 smsl-n100 dhcpcd[945]: eth0: adding default route Apr 15 18:21:22 smsl-n100 dhcpcd[613]: eth0: leased 192.168.160.11 for 8718 seconds Apr 15 18:21:22 smsl-n100 dhcpcd[613]: eth0: adding route to 192.168.160.0/24 Apr 15 18:21:22 smsl-n100 dhcpcd[613]: eth0: changing default route via 192.168.160.1 Apr 15 18:21:22 smsl-n100 avahi-daemon[526]: Registering new address record for 192.168.160.11 on eth0.IPv4. Apr 15 18:21:22 smsl-n100 dhcpcd[945]: eth0: deleted default route Apr 15 18:21:22 smsl-n100 ntpd[944]: ntpd exiting on signal 15 (Terminated) Apr 15 18:21:22 smsl-n100 systemd[1]: Stopping Network Time Service... Apr 15 18:21:22 smsl-n100 systemd[1]: ntp.service: Succeeded. Apr 15 18:21:22 smsl-n100 systemd[1]: Stopped Network Time Service. Apr 15 18:21:22 smsl-n100 systemd[1]: Starting Network Time Service... Apr 15 18:21:22 smsl-n100 ntpd[2417]: ntpd 4.2.8p12@1.3728-o (1): Starting Apr 15 18:21:22 smsl-n100 ntpd[2417]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Apr 15 18:21:22 smsl-n100 systemd[1]: Started Network Time Service. Apr 15 18:21:22 smsl-n100 ntpd[2424]: proto: precision = 1.166 usec (-20) Apr 15 18:21:22 smsl-n100 dhcpcd[613]: eth0: deleting route to 169.254.0.0/16 Apr 15 18:21:22 smsl-n100 ntpd[2424]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Apr 15 18:21:22 smsl-n100 ntpd[2424]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Apr 15 18:21:22 smsl-n100 ntpd[2424]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 840 days ago Apr 15 18:21:22 smsl-n100 avahi-daemon[526]: Withdrawing address record for 169.254.83.202 on eth0. Apr 15 18:21:22 smsl-n100 avahi-daemon[526]: Leaving mDNS multicast group on interface eth0.IPv4 with address 169.254.83.202. Apr 15 18:21:22 smsl-n100 ntpd[2424]: Listen and drop on 0 v6wildcard [::]:123 Apr 15 18:21:22 smsl-n100 avahi-daemon[526]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.160.11. Apr 15 18:21:22 smsl-n100 ntpd[2424]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Apr 15 18:21:22 smsl-n100 ntpd[2424]: Listen normally on 2 lo 127.0.0.1:123 Apr 15 18:21:22 smsl-n100 ntpd[2424]: Listen normally on 3 eth0 192.168.160.11:123 Apr 15 18:21:22 smsl-n100 ntpd[2424]: Listening on routing socket on fd #20 for interface updates Apr 15 18:21:22 smsl-n100 ntpd[2424]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Apr 15 18:21:22 smsl-n100 ntpd[2424]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Apr 15 18:21:23 smsl-n100 ntpd[2424]: Soliciting pool server 167.235.139.237 Apr 15 18:21:24 smsl-n100 ntpd[2424]: Soliciting pool server 157.90.16.34 Apr 15 18:21:24 smsl-n100 dhcpcd[945]: eth0: leased 192.168.160.11 for 8716 seconds Apr 15 18:21:25 smsl-n100 dhcpcd[945]: eth0: adding route to 192.168.160.0/24 Apr 15 18:21:25 smsl-n100 dhcpcd[945]: eth0: adding default route via 192.168.160.1 Apr 15 18:21:25 smsl-n100 ntpd[2424]: Soliciting pool server 194.59.205.229 Apr 15 18:21:25 smsl-n100 volumio[1059]: info: Reporting MCU Network Status: 1 Apr 15 18:21:25 smsl-n100 volumio[1059]: info: Volumio Network Manager: Network status updated: 1 Apr 15 18:21:25 smsl-n100 volumio[1059]: info: CoreStateMachine::pushState Apr 15 18:21:25 smsl-n100 volumio[1059]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 18:21:25 smsl-n100 volumio[1059]: info: CoreCommandRouter::volumioPushState Apr 15 18:21:25 smsl-n100 volumio[1059]: error: Serial API: Failed to decode command: 100, message: undefined Apr 15 18:21:25 smsl-n100 volumio[1059]: error: Serial API: Failed to decode command: MAXVOL, message: Apr 15 18:21:25 smsl-n100 volumio[1059]: error: Serial API: Failed to decode command: 100, message: undefined Apr 15 18:21:25 smsl-n100 volumio[1059]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Apr 15 18:21:25 smsl-n100 volumio[1059]: error: Serial API: Failed to decode command: 1, message: undefined Apr 15 18:21:25 smsl-n100 ntpd[2424]: ntpd exiting on signal 15 (Terminated) Apr 15 18:21:25 smsl-n100 ntpd[2424]: 167.235.139.237 local addr 192.168.160.11 -> Apr 15 18:21:25 smsl-n100 ntpd[2424]: 157.90.16.34 local addr 192.168.160.11 -> Apr 15 18:21:25 smsl-n100 systemd[1]: Stopping Network Time Service... Apr 15 18:21:25 smsl-n100 ntpd[2424]: 194.59.205.229 local addr 192.168.160.11 -> Apr 15 18:21:25 smsl-n100 systemd[1]: ntp.service: Succeeded. Apr 15 18:21:25 smsl-n100 systemd[1]: Stopped Network Time Service. Apr 15 18:21:25 smsl-n100 systemd[1]: Starting Network Time Service... Apr 15 18:21:25 smsl-n100 ntpd[2485]: ntpd 4.2.8p12@1.3728-o (1): Starting Apr 15 18:21:25 smsl-n100 ntpd[2485]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Apr 15 18:21:25 smsl-n100 systemd[1]: Started Network Time Service. Apr 15 18:21:25 smsl-n100 ntpd[2491]: proto: precision = 1.166 usec (-20) Apr 15 18:21:25 smsl-n100 dhcpcd[945]: eth0: deleting route to 169.254.0.0/16 Apr 15 18:21:25 smsl-n100 ntpd[2491]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Apr 15 18:21:25 smsl-n100 ntpd[2491]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Apr 15 18:21:25 smsl-n100 ntpd[2491]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 840 days ago Apr 15 18:21:25 smsl-n100 ntpd[2491]: Listen and drop on 0 v6wildcard [::]:123 Apr 15 18:21:25 smsl-n100 ntpd[2491]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Apr 15 18:21:25 smsl-n100 ntpd[2491]: Listen normally on 2 lo 127.0.0.1:123 Apr 15 18:21:25 smsl-n100 ntpd[2491]: Listen normally on 3 eth0 192.168.160.11:123 Apr 15 18:21:25 smsl-n100 ntpd[2491]: Listening on routing socket on fd #20 for interface updates Apr 15 18:21:25 smsl-n100 ntpd[2491]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Apr 15 18:21:25 smsl-n100 ntpd[2491]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Apr 15 18:21:26 smsl-n100 ntpd[2491]: Soliciting pool server 217.197.91.176 Apr 15 18:21:26 smsl-n100 volumio[1059]: info: Discovery: adding e2c5ed56-60a3-4ebc-9041-fb4e312aba0d Apr 15 18:21:26 smsl-n100 volumio[1059]: info: Discovery: Found device SMSL N100 Apr 15 18:21:26 smsl-n100 volumio[1059]: info: CoreCommandRouter::volumioGetState Apr 15 18:21:26 smsl-n100 volumio[1059]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 15 18:21:26 smsl-n100 volumio[1059]: Error: The value NaN is not a number Apr 15 18:21:26 smsl-n100 volumio[1059]: at Config.forceToType (/volumio/node_modules/v-conf/index.js:322:20) Apr 15 18:21:26 smsl-n100 volumio[1059]: at Config.set (/volumio/node_modules/v-conf/index.js:153:25) Apr 15 18:21:26 smsl-n100 volumio[1059]: at ControllerVolumioDiscovery.updateMultiroomDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:323:25) Apr 15 18:21:26 smsl-n100 volumio[1059]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:273:10) Apr 15 18:21:26 smsl-n100 volumio[1059]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12) Apr 15 18:21:26 smsl-n100 volumio[1059]: at Browser.emit (events.js:400:28) Apr 15 18:21:26 smsl-n100 volumio[1059]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) Apr 15 18:21:26 smsl-n100 volumio[1059]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) Apr 15 18:21:26 smsl-n100 volumio[1059]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5) Apr 15 18:21:26 smsl-n100 volumio[1059]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21) Apr 15 18:21:26 smsl-n100 volumio[1059]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 15 18:21:27 smsl-n100 ntpd[2491]: Soliciting pool server 188.174.253.188 Apr 15 18:21:27 smsl-n100 ntpd[2491]: Soliciting pool server 195.201.173.232 Apr 15 18:21:27 smsl-n100 sudo[2519]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-15 18:20 Apr 15 18:21:27 smsl-n100 sudo[2519]: 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"