-- Logs begin at Thu 2019-02-14 13:11:59 MSK, end at Sun 2025-10-26 16:59:19 MSK. -- Oct 26 16:58:00 smsl-n100 volumio[790]: info: CoreCommandRouter::volumioPlay Oct 26 16:58:00 smsl-n100 volumio[790]: info: CoreStateMachine::play index undefined Oct 26 16:58:00 smsl-n100 volumio[790]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 26 16:58:00 smsl-n100 volumio[790]: info: CorePlayQueue::getTrack 0 Oct 26 16:58:00 smsl-n100 volumio[790]: info: CoreStateMachine::startPlaybackTimer Oct 26 16:58:00 smsl-n100 volumio[790]: info: CorePlayQueue::getTrack 0 Oct 26 16:58:00 smsl-n100 volumio[790]: info: [1761487080254] ControllerQobuz::clearAddPlayTrack Oct 26 16:58:00 smsl-n100 volumio[790]: verbose: ControllerMpd::sendMpdCommand stop Oct 26 16:58:00 smsl-n100 volumio[790]: info: sendMpdCommand stop took 6 milliseconds Oct 26 16:58:00 smsl-n100 volumio[790]: verbose: ControllerMpd::sendMpdCommand clear Oct 26 16:58:00 smsl-n100 volumio[790]: info: sendMpdCommand clear took 3 milliseconds Oct 26 16:58:00 smsl-n100 volumio[790]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/331774606" Oct 26 16:58:00 smsl-n100 volumio[790]: info: Oct 26 16:58:00 smsl-n100 volumio[790]: ---------------------------- MPD announces system playlist update Oct 26 16:58:00 smsl-n100 volumio[790]: info: Ignoring MPD Status Update Oct 26 16:58:00 smsl-n100 volumio[790]: info: Oct 26 16:58:00 smsl-n100 volumio[790]: ---------------------------- MPD announces system playlist update Oct 26 16:58:00 smsl-n100 volumio[790]: info: Ignoring MPD Status Update Oct 26 16:58:00 smsl-n100 volumio[790]: info: Oct 26 16:58:00 smsl-n100 volumio[790]: ---------------------------- MPD announces system playlist update Oct 26 16:58:00 smsl-n100 volumio[790]: info: Ignoring MPD Status Update Oct 26 16:58:00 smsl-n100 volumio[790]: error: Upnp client error: Error: This socket has been ended by the other party Oct 26 16:58:00 smsl-n100 volumio[790]: STREAMING PROXY: Handling url /?data=qobuz://song/331774606 Oct 26 16:58:00 smsl-n100 volumio[790]: info: Executing endpoint getStreamUrlqobuz Oct 26 16:58:00 smsl-n100 volumio[790]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Oct 26 16:58:00 smsl-n100 volumio[790]: info: getStreamUrl took 536 milliseconds Oct 26 16:58:01 smsl-n100 volumio[790]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=5521754&eid=331774606&fmt=6&profile=raw&app_id=539451548&cid=3279288&etsp=1761490680&hmac=O_hYzauwTenn4JCM09Yef-BMITo Oct 26 16:58:01 smsl-n100 volumio[790]: STREAMING PROXY: Response: 200, length: 37099346 Oct 26 16:58:01 smsl-n100 volumio[790]: error: updateQueue error: null Oct 26 16:58:01 smsl-n100 volumio[790]: error: updateQueue error: null Oct 26 16:58:01 smsl-n100 volumio[790]: error: updateQueue error: null Oct 26 16:58:01 smsl-n100 volumio[790]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/331774606" Oct 26 16:58:01 smsl-n100 volumio[790]: info: ------------------------------ 1051ms Oct 26 16:58:01 smsl-n100 volumio[790]: info: ------------------------------ 1045ms Oct 26 16:58:01 smsl-n100 volumio[790]: info: ------------------------------ 1039ms Oct 26 16:58:01 smsl-n100 volumio[790]: info: Oct 26 16:58:01 smsl-n100 volumio[790]: ---------------------------- MPD announces system playlist update Oct 26 16:58:01 smsl-n100 volumio[790]: info: Ignoring MPD Status Update Oct 26 16:58:01 smsl-n100 volumio[790]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/331774606" took 11 milliseconds Oct 26 16:58:01 smsl-n100 volumio[790]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 26 16:58:01 smsl-n100 volumio[790]: verbose: ControllerMpd::sendMpdCommand play Oct 26 16:58:01 smsl-n100 volumio[790]: STREAMING PROXY: Client dropped request, destroying Oct 26 16:58:01 smsl-n100 volumio[790]: info: Oct 26 16:58:01 smsl-n100 volumio[790]: ---------------------------- MPD announces system playlist update Oct 26 16:58:01 smsl-n100 volumio[790]: info: Ignoring MPD Status Update Oct 26 16:58:01 smsl-n100 volumio[790]: info: Oct 26 16:58:01 smsl-n100 volumio[790]: ---------------------------- MPD announces system playlist update Oct 26 16:58:01 smsl-n100 volumio[790]: info: Ignoring MPD Status Update Oct 26 16:58:01 smsl-n100 volumio[790]: info: ------------------------------ 30ms Oct 26 16:58:01 smsl-n100 volumio[790]: info: sendMpdCommand play took 23 milliseconds Oct 26 16:58:01 smsl-n100 volumio[790]: info: ------------------------------ 21ms Oct 26 16:58:01 smsl-n100 volumio[790]: STREAMING PROXY: Handling url /?data=qobuz://song/331774606 Oct 26 16:58:01 smsl-n100 volumio[790]: info: ------------------------------ 17ms Oct 26 16:58:01 smsl-n100 volumio[790]: info: Executing endpoint getStreamUrlqobuz Oct 26 16:58:01 smsl-n100 volumio[790]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Oct 26 16:58:01 smsl-n100 volumio[790]: info: getStreamUrl took 388 milliseconds Oct 26 16:58:01 smsl-n100 volumio[790]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=5521754&eid=331774606&fmt=6&profile=raw&app_id=539451548&cid=3279288&etsp=1761490681&hmac=oy5q6IMyLGRPpeGIM6TLM1rHewo Oct 26 16:58:01 smsl-n100 volumio[790]: STREAMING PROXY: Response: 200, length: 37099346 Oct 26 16:58:01 smsl-n100 volumio[790]: info: Oct 26 16:58:01 smsl-n100 volumio[790]: ---------------------------- MPD announces state update: player Oct 26 16:58:01 smsl-n100 volumio[790]: info: ControllerMpd::getState Oct 26 16:58:01 smsl-n100 volumio[790]: verbose: ControllerMpd::sendMpdCommand status Oct 26 16:58:01 smsl-n100 volumio[790]: info: Oct 26 16:58:01 smsl-n100 volumio[790]: ---------------------------- MPD announces state update: player Oct 26 16:58:01 smsl-n100 volumio[790]: info: ControllerMpd::getState Oct 26 16:58:01 smsl-n100 volumio[790]: verbose: ControllerMpd::sendMpdCommand status Oct 26 16:58:01 smsl-n100 volumio[790]: info: Oct 26 16:58:01 smsl-n100 volumio[790]: ---------------------------- MPD announces state update: player Oct 26 16:58:01 smsl-n100 volumio[790]: info: ControllerMpd::getState Oct 26 16:58:01 smsl-n100 volumio[790]: verbose: ControllerMpd::sendMpdCommand status Oct 26 16:58:01 smsl-n100 volumio[790]: info: Oct 26 16:58:01 smsl-n100 volumio[790]: ---------------------------- MPD announces state update: player Oct 26 16:58:01 smsl-n100 volumio[790]: info: ControllerMpd::getState Oct 26 16:58:01 smsl-n100 volumio[790]: verbose: ControllerMpd::sendMpdCommand status Oct 26 16:58:02 smsl-n100 volumio[790]: info: sendMpdCommand status took 76 milliseconds Oct 26 16:58:02 smsl-n100 volumio[790]: info: sendMpdCommand status took 77 milliseconds Oct 26 16:58:02 smsl-n100 volumio[790]: info: sendMpdCommand status took 64 milliseconds Oct 26 16:58:02 smsl-n100 volumio[790]: verbose: ControllerMpd::parseState Oct 26 16:58:02 smsl-n100 volumio[790]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 16:58:02 smsl-n100 volumio[790]: verbose: ControllerMpd::parseState Oct 26 16:58:02 smsl-n100 volumio[790]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 16:58:02 smsl-n100 volumio[790]: verbose: ControllerMpd::parseState Oct 26 16:58:02 smsl-n100 volumio[790]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 16:58:02 smsl-n100 volumio[790]: info: Oct 26 16:58:02 smsl-n100 volumio[790]: ---------------------------- MPD announces state update: player Oct 26 16:58:02 smsl-n100 volumio[790]: info: ControllerMpd::getState Oct 26 16:58:02 smsl-n100 volumio[790]: verbose: ControllerMpd::sendMpdCommand status Oct 26 16:58:02 smsl-n100 volumio[790]: info: Oct 26 16:58:02 smsl-n100 volumio[790]: ---------------------------- MPD announces state update: player Oct 26 16:58:02 smsl-n100 volumio[790]: info: ControllerMpd::getState Oct 26 16:58:02 smsl-n100 volumio[790]: verbose: ControllerMpd::sendMpdCommand status Oct 26 16:58:02 smsl-n100 volumio[790]: info: sendMpdCommand status took 123 milliseconds Oct 26 16:58:02 smsl-n100 volumio[790]: info: sendMpdCommand playlistinfo took 78 milliseconds Oct 26 16:58:02 smsl-n100 volumio[790]: info: sendMpdCommand playlistinfo took 63 milliseconds Oct 26 16:58:02 smsl-n100 volumio[790]: info: sendMpdCommand playlistinfo took 60 milliseconds Oct 26 16:58:02 smsl-n100 volumio[790]: info: sendMpdCommand status took 47 milliseconds Oct 26 16:58:02 smsl-n100 volumio[790]: info: sendMpdCommand status took 28 milliseconds Oct 26 16:58:02 smsl-n100 volumio[790]: verbose: ControllerMpd::parseState Oct 26 16:58:02 smsl-n100 volumio[790]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 16:58:02 smsl-n100 volumio[790]: verbose: ControllerMpd::parseTrackInfo Oct 26 16:58:02 smsl-n100 volumio[790]: verbose: ControllerMpd::parseTrackInfo Oct 26 16:58:02 smsl-n100 volumio[790]: verbose: ControllerMpd::parseTrackInfo Oct 26 16:58:02 smsl-n100 volumio[790]: verbose: ControllerMpd::parseState Oct 26 16:58:02 smsl-n100 volumio[790]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 16:58:02 smsl-n100 volumio[790]: verbose: ControllerMpd::parseState Oct 26 16:58:02 smsl-n100 volumio[790]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 16:58:02 smsl-n100 volumio[790]: info: ControllerMpd::pushState Oct 26 16:58:02 smsl-n100 volumio[790]: info: CoreCommandRouter::servicePushState Oct 26 16:58:02 smsl-n100 volumio[790]: info: CorePlayQueue::getTrack 0 Oct 26 16:58:02 smsl-n100 volumio[790]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":383,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"217 Kbps","isStreaming":false,"title":"331774606","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/331774606","trackType":"qobuz"} Oct 26 16:58:02 smsl-n100 volumio[790]: verbose: CURRENT POSITION 0 Oct 26 16:58:02 smsl-n100 volumio[790]: info: CoreStateMachine::syncState stateService play Oct 26 16:58:02 smsl-n100 volumio[790]: info: CoreStateMachine::syncState currentStatus stop Oct 26 16:58:02 smsl-n100 volumio[790]: info: ControllerMpd::pushState Oct 26 16:58:02 smsl-n100 volumio[790]: info: CoreCommandRouter::servicePushState Oct 26 16:58:02 smsl-n100 volumio[790]: info: CorePlayQueue::getTrack 0 Oct 26 16:58:02 smsl-n100 volumio[790]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":383,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"235 Kbps","isStreaming":false,"title":"331774606","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/331774606","trackType":"qobuz"} Oct 26 16:58:02 smsl-n100 volumio[790]: verbose: CURRENT POSITION 0 Oct 26 16:58:02 smsl-n100 volumio[790]: info: CoreStateMachine::syncState stateService play Oct 26 16:58:02 smsl-n100 volumio[790]: info: CoreStateMachine::syncState currentStatus play Oct 26 16:58:02 smsl-n100 volumio[790]: info: Received an update from plugin. extracting info from payload Oct 26 16:58:02 smsl-n100 volumio[790]: info: CoreStateMachine::pushState Oct 26 16:58:02 smsl-n100 volumio[790]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 16:58:02 smsl-n100 volumio[790]: info: CoreCommandRouter::volumioPushState Oct 26 16:58:02 smsl-n100 volumio[790]: info: CoreStateMachine::pushState Oct 26 16:58:02 smsl-n100 volumio[790]: info: CoreCommandRouter::volumioPushState Oct 26 16:58:02 smsl-n100 volumio[790]: info: ControllerMpd::pushState Oct 26 16:58:02 smsl-n100 volumio[790]: info: CoreCommandRouter::servicePushState Oct 26 16:58:02 smsl-n100 volumio[790]: info: CorePlayQueue::getTrack 0 Oct 26 16:58:02 smsl-n100 volumio[790]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":383,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"239 Kbps","isStreaming":false,"title":"331774606","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/331774606","trackType":"qobuz"} Oct 26 16:58:02 smsl-n100 volumio[790]: verbose: CURRENT POSITION 0 Oct 26 16:58:02 smsl-n100 volumio[790]: info: CoreStateMachine::syncState stateService play Oct 26 16:58:02 smsl-n100 volumio[790]: info: CoreStateMachine::syncState currentStatus play Oct 26 16:58:02 smsl-n100 volumio[790]: info: Received an update from plugin. extracting info from payload Oct 26 16:58:02 smsl-n100 volumio[790]: info: CoreStateMachine::pushState Oct 26 16:58:02 smsl-n100 volumio[790]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 16:58:02 smsl-n100 volumio[790]: info: CoreCommandRouter::volumioPushState Oct 26 16:58:02 smsl-n100 volumio[790]: info: CoreStateMachine::pushState Oct 26 16:58:02 smsl-n100 volumio[790]: info: CoreCommandRouter::volumioPushState Oct 26 16:58:02 smsl-n100 volumio[790]: info: ------------------------------ 336ms Oct 26 16:58:02 smsl-n100 volumio[790]: info: ------------------------------ 384ms Oct 26 16:58:02 smsl-n100 volumio[790]: info: ------------------------------ 376ms Oct 26 16:58:02 smsl-n100 volumio[790]: info: sendMpdCommand playlistinfo took 184 milliseconds Oct 26 16:58:02 smsl-n100 volumio[790]: info: sendMpdCommand playlistinfo took 166 milliseconds Oct 26 16:58:02 smsl-n100 volumio[790]: info: sendMpdCommand playlistinfo took 165 milliseconds Oct 26 16:58:02 smsl-n100 volumio[790]: verbose: ControllerMpd::parseTrackInfo Oct 26 16:58:02 smsl-n100 volumio[790]: info: ControllerMpd::pushState Oct 26 16:58:02 smsl-n100 volumio[790]: info: CoreCommandRouter::servicePushState Oct 26 16:58:02 smsl-n100 volumio[790]: info: CorePlayQueue::getTrack 0 Oct 26 16:58:02 smsl-n100 volumio[790]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":383,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"239 Kbps","isStreaming":false,"title":"331774606","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/331774606","trackType":"qobuz"} Oct 26 16:58:02 smsl-n100 volumio[790]: verbose: CURRENT POSITION 0 Oct 26 16:58:02 smsl-n100 volumio[790]: info: CoreStateMachine::syncState stateService play Oct 26 16:58:02 smsl-n100 volumio[790]: info: CoreStateMachine::syncState currentStatus play Oct 26 16:58:02 smsl-n100 volumio[790]: info: Received an update from plugin. extracting info from payload Oct 26 16:58:02 smsl-n100 volumio[790]: info: CoreStateMachine::pushState Oct 26 16:58:02 smsl-n100 volumio[790]: info: CoreCommandRouter::volumioPushState Oct 26 16:58:02 smsl-n100 volumio[790]: info: CoreStateMachine::pushState Oct 26 16:58:02 smsl-n100 volumio[790]: info: CoreCommandRouter::volumioPushState Oct 26 16:58:02 smsl-n100 volumio[790]: info: ControllerMpd::pushState Oct 26 16:58:02 smsl-n100 volumio[790]: info: CoreCommandRouter::servicePushState Oct 26 16:58:02 smsl-n100 volumio[790]: info: CorePlayQueue::getTrack 0 Oct 26 16:58:02 smsl-n100 volumio[790]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":383,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"248 Kbps","isStreaming":false,"title":"331774606","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/331774606","trackType":"qobuz"} Oct 26 16:58:02 smsl-n100 volumio[790]: verbose: CURRENT POSITION 0 Oct 26 16:58:02 smsl-n100 volumio[790]: info: CoreStateMachine::syncState stateService play Oct 26 16:58:02 smsl-n100 volumio[790]: info: CoreStateMachine::syncState currentStatus play Oct 26 16:58:02 smsl-n100 volumio[790]: info: Received an update from plugin. extracting info from payload Oct 26 16:58:02 smsl-n100 volumio[790]: info: CoreStateMachine::pushState Oct 26 16:58:02 smsl-n100 volumio[790]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 16:58:02 smsl-n100 volumio[790]: info: CoreCommandRouter::volumioPushState Oct 26 16:58:02 smsl-n100 volumio[790]: info: CoreStateMachine::pushState Oct 26 16:58:02 smsl-n100 volumio[790]: info: CoreCommandRouter::volumioPushState Oct 26 16:58:02 smsl-n100 volumio[790]: info: ControllerMpd::pushState Oct 26 16:58:02 smsl-n100 volumio[790]: info: CoreCommandRouter::servicePushState Oct 26 16:58:02 smsl-n100 volumio[790]: info: CorePlayQueue::getTrack 0 Oct 26 16:58:02 smsl-n100 volumio[790]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":383,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"248 Kbps","isStreaming":false,"title":"331774606","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/331774606","trackType":"qobuz"} Oct 26 16:58:02 smsl-n100 volumio[790]: verbose: CURRENT POSITION 0 Oct 26 16:58:02 smsl-n100 volumio[790]: info: CoreStateMachine::syncState stateService play Oct 26 16:58:02 smsl-n100 volumio[790]: info: CoreStateMachine::syncState currentStatus play Oct 26 16:58:02 smsl-n100 volumio[790]: info: Received an update from plugin. extracting info from payload Oct 26 16:58:02 smsl-n100 volumio[790]: info: CoreStateMachine::pushState Oct 26 16:58:02 smsl-n100 volumio[790]: info: CoreCommandRouter::volumioPushState Oct 26 16:58:02 smsl-n100 volumio[790]: info: CoreStateMachine::pushState Oct 26 16:58:02 smsl-n100 volumio[790]: info: CoreCommandRouter::volumioPushState Oct 26 16:58:02 smsl-n100 volumio[790]: info: ------------------------------ 558ms Oct 26 16:58:02 smsl-n100 volumio[790]: info: ------------------------------ 456ms Oct 26 16:58:02 smsl-n100 volumio[790]: info: ------------------------------ 446ms Oct 26 16:58:02 smsl-n100 volumio[790]: info: CoreStateMachine::pushState Oct 26 16:58:02 smsl-n100 volumio[790]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 16:58:02 smsl-n100 volumio[790]: info: CoreCommandRouter::volumioPushState Oct 26 16:58:02 smsl-n100 volumio[790]: error: Serial API: Failed to decode command: 100, message: undefined Oct 26 16:58:02 smsl-n100 volumio[790]: error: Serial API: Failed to decode command: MAXVOL, message: Oct 26 16:58:02 smsl-n100 volumio[790]: error: Serial API: Failed to decode command: 100, message: undefined Oct 26 16:58:02 smsl-n100 volumio[790]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Oct 26 16:58:05 smsl-n100 volumio[790]: info: CoreCommandRouter::volumioPause Oct 26 16:58:05 smsl-n100 volumio[790]: info: CoreStateMachine::pause Oct 26 16:58:05 smsl-n100 volumio[790]: info: CoreStateMachine::stPlaybackTimer Oct 26 16:58:05 smsl-n100 volumio[790]: info: CoreStateMachine::servicePause Oct 26 16:58:05 smsl-n100 volumio[790]: info: CorePlayQueue::getTrack 0 Oct 26 16:58:05 smsl-n100 volumio[790]: info: CoreCommandRouter::servicePause Oct 26 16:58:05 smsl-n100 volumio[790]: info: [1761487085609] ControllerQobuz::pause Oct 26 16:58:05 smsl-n100 volumio[790]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 26 16:58:05 smsl-n100 volumio[790]: info: ControllerMpd::pause Oct 26 16:58:05 smsl-n100 volumio[790]: verbose: ControllerMpd::sendMpdCommand pause Oct 26 16:58:05 smsl-n100 volumio[790]: info: Oct 26 16:58:05 smsl-n100 volumio[790]: ---------------------------- MPD announces state update: player Oct 26 16:58:05 smsl-n100 volumio[790]: info: sendMpdCommand pause took 9 milliseconds Oct 26 16:58:05 smsl-n100 volumio[790]: info: ControllerMpd::getState Oct 26 16:58:05 smsl-n100 volumio[790]: verbose: ControllerMpd::sendMpdCommand status Oct 26 16:58:05 smsl-n100 volumio[790]: info: Oct 26 16:58:05 smsl-n100 volumio[790]: ---------------------------- MPD announces state update: player Oct 26 16:58:05 smsl-n100 volumio[790]: info: ControllerMpd::getState Oct 26 16:58:05 smsl-n100 volumio[790]: verbose: ControllerMpd::sendMpdCommand status Oct 26 16:58:05 smsl-n100 volumio[790]: info: Oct 26 16:58:05 smsl-n100 volumio[790]: ---------------------------- MPD announces state update: player Oct 26 16:58:05 smsl-n100 volumio[790]: info: ControllerMpd::getState Oct 26 16:58:05 smsl-n100 volumio[790]: verbose: ControllerMpd::sendMpdCommand status Oct 26 16:58:05 smsl-n100 volumio[790]: info: sendMpdCommand status took 21 milliseconds Oct 26 16:58:05 smsl-n100 volumio[790]: info: sendMpdCommand status took 15 milliseconds Oct 26 16:58:05 smsl-n100 volumio[790]: verbose: ControllerMpd::parseState Oct 26 16:58:05 smsl-n100 volumio[790]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 16:58:05 smsl-n100 volumio[790]: verbose: ControllerMpd::parseState Oct 26 16:58:05 smsl-n100 volumio[790]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 16:58:05 smsl-n100 volumio[790]: info: sendMpdCommand status took 22 milliseconds Oct 26 16:58:05 smsl-n100 volumio[790]: info: sendMpdCommand playlistinfo took 12 milliseconds Oct 26 16:58:05 smsl-n100 volumio[790]: info: sendMpdCommand playlistinfo took 11 milliseconds Oct 26 16:58:05 smsl-n100 volumio[790]: verbose: ControllerMpd::parseState Oct 26 16:58:05 smsl-n100 volumio[790]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 16:58:05 smsl-n100 volumio[790]: verbose: ControllerMpd::parseTrackInfo Oct 26 16:58:05 smsl-n100 volumio[790]: verbose: ControllerMpd::parseTrackInfo Oct 26 16:58:05 smsl-n100 volumio[790]: info: ControllerMpd::pushState Oct 26 16:58:05 smsl-n100 volumio[790]: info: CoreCommandRouter::servicePushState Oct 26 16:58:05 smsl-n100 volumio[790]: info: CorePlayQueue::getTrack 0 Oct 26 16:58:05 smsl-n100 volumio[790]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":4461,"duration":383,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"270 Kbps","isStreaming":false,"title":"331774606","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/331774606","trackType":"qobuz"} Oct 26 16:58:05 smsl-n100 volumio[790]: verbose: CURRENT POSITION 0 Oct 26 16:58:05 smsl-n100 volumio[790]: info: CoreStateMachine::syncState stateService pause Oct 26 16:58:05 smsl-n100 volumio[790]: info: CoreStateMachine::syncState currentStatus pause Oct 26 16:58:05 smsl-n100 volumio[790]: info: CoreStateMachine::pushState Oct 26 16:58:05 smsl-n100 volumio[790]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 16:58:05 smsl-n100 volumio[790]: info: CoreCommandRouter::volumioPushState Oct 26 16:58:05 smsl-n100 volumio[790]: info: CoreStateMachine::stPlaybackTimer Oct 26 16:58:05 smsl-n100 volumio[790]: info: ControllerMpd::pushState Oct 26 16:58:05 smsl-n100 volumio[790]: info: CoreCommandRouter::servicePushState Oct 26 16:58:05 smsl-n100 volumio[790]: info: CorePlayQueue::getTrack 0 Oct 26 16:58:05 smsl-n100 volumio[790]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":4461,"duration":383,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"270 Kbps","isStreaming":false,"title":"331774606","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/331774606","trackType":"qobuz"} Oct 26 16:58:05 smsl-n100 volumio[790]: verbose: CURRENT POSITION 0 Oct 26 16:58:05 smsl-n100 volumio[790]: info: CoreStateMachine::syncState stateService pause Oct 26 16:58:05 smsl-n100 volumio[790]: info: CoreStateMachine::syncState currentStatus pause Oct 26 16:58:05 smsl-n100 volumio[790]: info: CoreStateMachine::pushState Oct 26 16:58:05 smsl-n100 volumio[790]: info: CoreCommandRouter::volumioPushState Oct 26 16:58:05 smsl-n100 volumio[790]: info: CoreStateMachine::stPlaybackTimer Oct 26 16:58:05 smsl-n100 volumio[790]: info: ------------------------------ 152ms Oct 26 16:58:05 smsl-n100 volumio[790]: info: ------------------------------ 143ms Oct 26 16:58:05 smsl-n100 volumio[790]: info: sendMpdCommand playlistinfo took 105 milliseconds Oct 26 16:58:05 smsl-n100 volumio[790]: verbose: ControllerMpd::parseTrackInfo Oct 26 16:58:05 smsl-n100 volumio[790]: info: ControllerMpd::pushState Oct 26 16:58:05 smsl-n100 volumio[790]: info: CoreCommandRouter::servicePushState Oct 26 16:58:05 smsl-n100 volumio[790]: info: CorePlayQueue::getTrack 0 Oct 26 16:58:05 smsl-n100 volumio[790]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":4461,"duration":383,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"270 Kbps","isStreaming":false,"title":"331774606","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/331774606","trackType":"qobuz"} Oct 26 16:58:05 smsl-n100 volumio[790]: verbose: CURRENT POSITION 0 Oct 26 16:58:05 smsl-n100 volumio[790]: info: CoreStateMachine::syncState stateService pause Oct 26 16:58:05 smsl-n100 volumio[790]: info: CoreStateMachine::syncState currentStatus pause Oct 26 16:58:05 smsl-n100 volumio[790]: info: CoreStateMachine::pushState Oct 26 16:58:05 smsl-n100 volumio[790]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 16:58:05 smsl-n100 volumio[790]: info: CoreCommandRouter::volumioPushState Oct 26 16:58:05 smsl-n100 volumio[790]: info: CoreStateMachine::stPlaybackTimer Oct 26 16:58:05 smsl-n100 volumio[790]: info: ------------------------------ 191ms Oct 26 16:58:05 smsl-n100 volumio[790]: info: CoreStateMachine::pushState Oct 26 16:58:05 smsl-n100 volumio[790]: info: CoreCommandRouter::volumioPushState Oct 26 16:58:05 smsl-n100 volumio[790]: error: Serial API: Failed to decode command: 100, message: undefined Oct 26 16:58:05 smsl-n100 volumio[790]: error: Serial API: Failed to decode command: MAXVOL, message: Oct 26 16:58:05 smsl-n100 volumio[790]: error: Serial API: Failed to decode command: 100, message: undefined Oct 26 16:58:05 smsl-n100 volumio[790]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Oct 26 16:58:06 smsl-n100 volumio[790]: STREAMING PROXY: Client dropped request, destroying Oct 26 16:58:07 smsl-n100 volumio[790]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 26 16:58:07 smsl-n100 volumio[790]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 26 16:58:07 smsl-n100 volumio[790]: info: Discovery: Getting this device information Oct 26 16:58:07 smsl-n100 volumio[790]: info: CoreCommandRouter::volumioGetState Oct 26 16:58:07 smsl-n100 volumio[790]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 26 16:58:17 smsl-n100 volumio[790]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 26 16:58:17 smsl-n100 volumio[790]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 26 16:58:17 smsl-n100 volumio[790]: info: Retrieving Cloud Streaming UI Oct 26 16:58:17 smsl-n100 volumio[790]: info: Getting Tidal Cloud Configuration Oct 26 16:58:17 smsl-n100 volumio[790]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 26 16:58:17 smsl-n100 volumio[790]: info: Getting Qobuz Cloud Configuration Oct 26 16:58:17 smsl-n100 volumio[790]: info: Asking plugin for UI Config Oct 26 16:58:17 smsl-n100 volumio[790]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 26 16:58:17 smsl-n100 volumio[790]: info: Getting Spotify Cloud Configuration Oct 26 16:58:17 smsl-n100 volumio[790]: info: Asking plugin for UI Config Oct 26 16:58:17 smsl-n100 volumio[790]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 26 16:58:17 smsl-n100 volumio[790]: info: Saving Spotify Acccount Oct 26 16:58:17 smsl-n100 volumio[790]: info: Got it Oct 26 16:58:17 smsl-n100 volumio[790]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 26 16:58:17 smsl-n100 volumio[790]: info: Got Tidal Cloud Configuration Oct 26 16:58:17 smsl-n100 volumio[790]: info: Got it Oct 26 16:58:17 smsl-n100 volumio[790]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 26 16:58:17 smsl-n100 volumio[790]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 26 16:58:17 smsl-n100 volumio[790]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 26 16:58:17 smsl-n100 volumio[790]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 26 16:58:17 smsl-n100 volumio[790]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 26 16:58:17 smsl-n100 volumio[790]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 26 16:58:17 smsl-n100 volumio[790]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 26 16:58:17 smsl-n100 volumio[790]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 26 16:58:17 smsl-n100 volumio[790]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 26 16:58:17 smsl-n100 volumio[790]: info: CoreCommandRouter::volumioGetBrowseSources Oct 26 16:58:17 smsl-n100 volumio[790]: info: CoreCommandRouter::volumioGetBrowseSources Oct 26 16:58:17 smsl-n100 volumio[790]: info: CoreCommandRouter::volumioGetBrowseSources Oct 26 16:58:18 smsl-n100 volumio[790]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 26 16:58:18 smsl-n100 volumio[790]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 26 16:58:22 smsl-n100 volumio[790]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 26 16:58:26 smsl-n100 volumio[790]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 26 16:58:30 smsl-n100 volumio[790]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 26 16:58:34 smsl-n100 volumio[790]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 26 16:58:37 smsl-n100 volumio[790]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 26 16:58:37 smsl-n100 volumio[790]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 26 16:58:37 smsl-n100 volumio[790]: info: Discovery: Getting this device information Oct 26 16:58:37 smsl-n100 volumio[790]: info: CoreCommandRouter::volumioGetState Oct 26 16:58:37 smsl-n100 volumio[790]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 26 16:58:42 smsl-n100 volumio[790]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 26 16:58:42 smsl-n100 volumio[790]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 26 16:58:42 smsl-n100 volumio[790]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 16:58:42 smsl-n100 volumio[790]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 26 16:58:42 smsl-n100 volumio[790]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 26 16:58:42 smsl-n100 volumio[790]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 26 16:58:42 smsl-n100 volumio[790]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 26 16:58:42 smsl-n100 volumio[790]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 26 16:58:42 smsl-n100 volumio[790]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 26 16:58:42 smsl-n100 volumio[790]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 26 16:58:42 smsl-n100 volumio[790]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 26 16:58:42 smsl-n100 volumio[790]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 26 16:58:42 smsl-n100 volumio[790]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 26 16:58:42 smsl-n100 volumio[790]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Oct 26 16:58:42 smsl-n100 volumio[790]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 16:58:42 smsl-n100 volumio[790]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 16:58:50 smsl-n100 volumio[790]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 26 16:58:50 smsl-n100 volumio[790]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 26 16:58:50 smsl-n100 volumio[790]: info: Retrieving Cloud Streaming UI Oct 26 16:58:50 smsl-n100 volumio[790]: info: Getting Tidal Cloud Configuration Oct 26 16:58:50 smsl-n100 volumio[790]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 26 16:58:50 smsl-n100 volumio[790]: info: Getting Qobuz Cloud Configuration Oct 26 16:58:50 smsl-n100 volumio[790]: info: Asking plugin for UI Config Oct 26 16:58:50 smsl-n100 volumio[790]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 26 16:58:50 smsl-n100 volumio[790]: info: Getting Spotify Cloud Configuration Oct 26 16:58:50 smsl-n100 volumio[790]: info: Asking plugin for UI Config Oct 26 16:58:50 smsl-n100 volumio[790]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 26 16:58:50 smsl-n100 volumio[790]: info: Saving Spotify Acccount Oct 26 16:58:50 smsl-n100 volumio[790]: info: Got it Oct 26 16:58:50 smsl-n100 volumio[790]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 26 16:58:50 smsl-n100 volumio[790]: info: Got Tidal Cloud Configuration Oct 26 16:58:50 smsl-n100 volumio[790]: info: Got it Oct 26 16:58:50 smsl-n100 volumio[790]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 26 16:58:50 smsl-n100 volumio[790]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 26 16:58:50 smsl-n100 volumio[790]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 26 16:58:50 smsl-n100 volumio[790]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 26 16:58:50 smsl-n100 volumio[790]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 26 16:58:50 smsl-n100 volumio[790]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 26 16:58:50 smsl-n100 volumio[790]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 26 16:58:50 smsl-n100 volumio[790]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 26 16:58:50 smsl-n100 volumio[790]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 26 16:58:50 smsl-n100 volumio[790]: info: CoreCommandRouter::volumioGetBrowseSources Oct 26 16:58:50 smsl-n100 volumio[790]: info: CoreCommandRouter::volumioGetBrowseSources Oct 26 16:58:50 smsl-n100 volumio[790]: info: CoreCommandRouter::volumioGetBrowseSources Oct 26 16:58:50 smsl-n100 volumio[790]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 26 16:58:50 smsl-n100 volumio[790]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 26 16:58:54 smsl-n100 volumio[790]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 26 16:58:57 smsl-n100 volumio[790]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 26 16:58:57 smsl-n100 volumio[790]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 26 16:58:57 smsl-n100 volumio[790]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Oct 26 16:58:57 smsl-n100 sudo[2164]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Oct 26 16:58:57 smsl-n100 sudo[2164]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 16:58:57 smsl-n100 sudo[2164]: pam_unix(sudo:session): session closed for user root Oct 26 16:58:57 smsl-n100 sudo[2169]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 26 16:58:57 smsl-n100 sudo[2169]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 16:58:57 smsl-n100 sudo[2169]: pam_unix(sudo:session): session closed for user root Oct 26 16:58:57 smsl-n100 sudo[2177]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 26 16:58:57 smsl-n100 sudo[2177]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 16:58:57 smsl-n100 sudo[2177]: pam_unix(sudo:session): session closed for user root Oct 26 16:58:57 smsl-n100 sudo[2183]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 26 16:58:57 smsl-n100 sudo[2183]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 16:58:57 smsl-n100 sudo[2183]: pam_unix(sudo:session): session closed for user root Oct 26 16:58:57 smsl-n100 sudo[2189]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 26 16:58:57 smsl-n100 sudo[2189]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 16:58:57 smsl-n100 volumio[790]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Oct 26 16:58:57 smsl-n100 volumio[790]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Oct 26 16:58:57 smsl-n100 sudo[2189]: pam_unix(sudo:session): session closed for user root Oct 26 16:58:57 smsl-n100 sudo[2192]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 26 16:58:57 smsl-n100 sudo[2192]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 16:58:57 smsl-n100 sudo[2192]: pam_unix(sudo:session): session closed for user root Oct 26 16:59:07 smsl-n100 volumio[790]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 26 16:59:07 smsl-n100 volumio[790]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 26 16:59:07 smsl-n100 volumio[790]: info: Discovery: Getting this device information Oct 26 16:59:07 smsl-n100 volumio[790]: info: CoreCommandRouter::volumioGetState Oct 26 16:59:07 smsl-n100 volumio[790]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 26 16:59:12 smsl-n100 volumio[790]: info: CALLMETHOD: system_controller network saveWirelessNet [object Object] Oct 26 16:59:12 smsl-n100 volumio[790]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNet Oct 26 16:59:12 smsl-n100 sudo[2214]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/network/interfaces Oct 26 16:59:12 smsl-n100 sudo[2214]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 16:59:12 smsl-n100 sudo[2214]: pam_unix(sudo:session): session closed for user root Oct 26 16:59:12 smsl-n100 sudo[2216]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/dhcpcd.conf Oct 26 16:59:12 smsl-n100 sudo[2216]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 16:59:12 smsl-n100 sudo[2216]: pam_unix(sudo:session): session closed for user root Oct 26 16:59:12 smsl-n100 volumio[790]: info: Permissions for /etc/network/interfaces set Oct 26 16:59:12 smsl-n100 volumio[790]: info: Restarting Wireless Network Oct 26 16:59:12 smsl-n100 volumio[790]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Oct 26 16:59:12 smsl-n100 volumio[790]: info: Discovery: Restarting Advertising due to device name change Oct 26 16:59:12 smsl-n100 sudo[2219]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Oct 26 16:59:12 smsl-n100 sudo[2219]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 16:59:12 smsl-n100 systemd[1]: Starting Wireless Services... Oct 26 16:59:13 smsl-n100 wireless.js[2221]: Cleaning previous... Oct 26 16:59:13 smsl-n100 volumio[790]: info: Discovery: A device disappeared from network Oct 26 16:59:13 smsl-n100 sudo[2235]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Oct 26 16:59:13 smsl-n100 sudo[2235]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 16:59:13 smsl-n100 sudo[2235]: pam_unix(sudo:session): session closed for user root Oct 26 16:59:13 smsl-n100 sudo[2237]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Oct 26 16:59:13 smsl-n100 sudo[2237]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 16:59:13 smsl-n100 sudo[2237]: pam_unix(sudo:session): session closed for user root Oct 26 16:59:14 smsl-n100 wireless.js[2221]: Stopped aP Oct 26 16:59:14 smsl-n100 wireless.js[2221]: Wireless Networking DISABLED, not starting wireless flow Oct 26 16:59:14 smsl-n100 iwconfig[2244]: Error for wireless request "Set Power Management" (8B2C) : Oct 26 16:59:14 smsl-n100 iwconfig[2244]: SET failed on device wlan0 ; Operation not permitted. Oct 26 16:59:14 smsl-n100 systemd[1]: wireless.service: Succeeded. Oct 26 16:59:14 smsl-n100 systemd[1]: Started Wireless Services. Oct 26 16:59:14 smsl-n100 sudo[2219]: pam_unix(sudo:session): session closed for user root Oct 26 16:59:17 smsl-n100 volumio[790]: info: Discovery: Started advertising with name: SMSL N100 Oct 26 16:59:18 smsl-n100 ntpd[958]: kernel reports TIME_ERROR: 0x4041: Clock Unsynchronized Oct 26 16:59:18 smsl-n100 volumio[790]: info: Discovery: adding 201f8813-a192-4278-b6f0-cfb93e816c09 Oct 26 16:59:18 smsl-n100 volumio[790]: info: Discovery: Found device SMSL N100 Oct 26 16:59:18 smsl-n100 volumio[790]: info: CoreCommandRouter::volumioGetState Oct 26 16:59:18 smsl-n100 volumio[790]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 26 16:59:18 smsl-n100 volumio[790]: Error: The value NaN is not a number Oct 26 16:59:18 smsl-n100 volumio[790]: at Config.forceToType (/volumio/node_modules/v-conf/index.js:322:20) Oct 26 16:59:18 smsl-n100 volumio[790]: at Config.set (/volumio/node_modules/v-conf/index.js:153:25) Oct 26 16:59:18 smsl-n100 volumio[790]: at ControllerVolumioDiscovery.updateMultiroomDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:323:25) Oct 26 16:59:18 smsl-n100 volumio[790]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:273:10) Oct 26 16:59:18 smsl-n100 volumio[790]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12) Oct 26 16:59:18 smsl-n100 volumio[790]: at Browser.emit (events.js:400:28) Oct 26 16:59:18 smsl-n100 volumio[790]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) Oct 26 16:59:18 smsl-n100 volumio[790]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) Oct 26 16:59:18 smsl-n100 volumio[790]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5) Oct 26 16:59:18 smsl-n100 volumio[790]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21) Oct 26 16:59:18 smsl-n100 volumio[790]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 Oct 26 16:59:18 smsl-n100 volumio[790]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7) Oct 26 16:59:18 smsl-n100 volumio[790]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) Oct 26 16:59:18 smsl-n100 volumio[790]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 26 16:59:19 smsl-n100 sudo[2314]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-26 16:58 Oct 26 16:59:19 smsl-n100 sudo[2314]: 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"