-- Logs begin at Wed 2025-04-23 21:30:07 EDT, end at Wed 2025-04-23 21:50:49 EDT. -- Apr 23 21:49:01 smsl-n100 volumio[1061]: info: CALLMETHOD: music_service mpd savePlaybackOptions [object Object] Apr 23 21:49:01 smsl-n100 volumio[1061]: info: CoreCommandRouter::executeOnPlugin: mpd , savePlaybackOptions Apr 23 21:49:01 smsl-n100 volumio[1061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 23 21:49:01 smsl-n100 volumio[1061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 23 21:49:01 smsl-n100 volumio[1061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 23 21:49:01 smsl-n100 volumio[1061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 23 21:49:01 smsl-n100 volumio[1061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 23 21:49:01 smsl-n100 volumio[1061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 23 21:49:01 smsl-n100 volumio[1061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 23 21:49:01 smsl-n100 sudo[5284]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 23 21:49:01 smsl-n100 sudo[5284]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 21:49:01 smsl-n100 sudo[5286]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 23 21:49:01 smsl-n100 sudo[5286]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 21:49:01 smsl-n100 sudo[5284]: pam_unix(sudo:session): session closed for user root Apr 23 21:49:01 smsl-n100 volumio[1061]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 23 21:49:01 smsl-n100 volumio[1061]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 23 21:49:01 smsl-n100 systemd[1]: Stopping Music Player Daemon... Apr 23 21:49:01 smsl-n100 volumio[1061]: STREAMING PROXY: Client dropped request, destroying Apr 23 21:49:01 smsl-n100 systemd[1]: mpd.service: Succeeded. Apr 23 21:49:01 smsl-n100 systemd[1]: Stopped Music Player Daemon. Apr 23 21:49:01 smsl-n100 volumio[1061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 23 21:49:01 smsl-n100 systemd[1]: Starting Music Player Daemon... Apr 23 21:49:01 smsl-n100 volumio[1061]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 23 21:49:01 smsl-n100 volumio[1061]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 23 21:49:01 smsl-n100 volumio[1061]: info: MPD Permissions set Apr 23 21:49:01 smsl-n100 volumio[1061]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 23 21:49:02 smsl-n100 sudo[5295]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 23 21:49:02 smsl-n100 sudo[5295]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 21:49:02 smsl-n100 sudo[5295]: pam_unix(sudo:session): session closed for user root Apr 23 21:49:02 smsl-n100 volumio[1061]: error: Upnp client error: Error: This socket has been ended by the other party Apr 23 21:49:04 smsl-n100 mpd[5297]: Apr 23 21:49 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 23 21:49:04 smsl-n100 systemd[1]: Started Music Player Daemon. Apr 23 21:49:04 smsl-n100 sudo[5286]: pam_unix(sudo:session): session closed for user root Apr 23 21:49:04 smsl-n100 volumio[1061]: error: updateQueue error: null Apr 23 21:49:09 smsl-n100 volumio[1061]: info: CoreCommandRouter::volumioGetState Apr 23 21:49:10 smsl-n100 volumio[1061]: info: CoreCommandRouter::volumioPrevious Apr 23 21:49:10 smsl-n100 volumio[1061]: info: CoreStateMachine::previous Apr 23 21:49:10 smsl-n100 volumio[1061]: info: [1745459350455] ControllerQobuz::seek Apr 23 21:49:10 smsl-n100 volumio[1061]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 23 21:49:10 smsl-n100 volumio[1061]: info: ControllerMpd::seek Apr 23 21:49:10 smsl-n100 volumio[1061]: error: [2@0] {seek} Bad song index Apr 23 21:49:11 smsl-n100 volumio[1061]: info: Executing endpoint metavolumio Apr 23 21:49:11 smsl-n100 volumio[1061]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 23 21:49:11 smsl-n100 volumio[1061]: info: Executing endpoint metavolumio Apr 23 21:49:11 smsl-n100 volumio[1061]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 23 21:49:11 smsl-n100 volumio[1061]: info: Executing endpoint metavolumio Apr 23 21:49:11 smsl-n100 volumio[1061]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 23 21:49:16 smsl-n100 volumio[1061]: info: CoreCommandRouter::volumioPlay Apr 23 21:49:16 smsl-n100 volumio[1061]: info: CoreStateMachine::play index 0 Apr 23 21:49:16 smsl-n100 volumio[1061]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 23 21:49:16 smsl-n100 volumio[1061]: info: CoreStateMachine::stop Apr 23 21:49:16 smsl-n100 volumio[1061]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 23 21:49:16 smsl-n100 volumio[1061]: info: CoreStateMachine::stPlaybackTimer Apr 23 21:49:16 smsl-n100 volumio[1061]: info: CoreStateMachine::updateTrackBlock Apr 23 21:49:16 smsl-n100 volumio[1061]: info: CorePlayQueue::getTrackBlock Apr 23 21:49:16 smsl-n100 volumio[1061]: info: CoreStateMachine::pushState Apr 23 21:49:16 smsl-n100 volumio[1061]: info: CorePlayQueue::getTrack 0 Apr 23 21:49:16 smsl-n100 volumio[1061]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 23 21:49:16 smsl-n100 volumio[1061]: info: CoreCommandRouter::volumioPushState Apr 23 21:49:16 smsl-n100 volumio[1061]: info: MRS: Pushing multiroomSync output update for this device Apr 23 21:49:16 smsl-n100 volumio[1061]: info: MRS: Pushing multiroomSync output Apr 23 21:49:16 smsl-n100 volumio[1061]: info: CoreStateMachine::serviceStop Apr 23 21:49:16 smsl-n100 volumio[1061]: info: CorePlayQueue::getTrack 0 Apr 23 21:49:16 smsl-n100 volumio[1061]: info: CoreCommandRouter::serviceStop Apr 23 21:49:16 smsl-n100 volumio[1061]: info: [1745459356269] ControllerQobuz::stop Apr 23 21:49:16 smsl-n100 volumio[1061]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 23 21:49:16 smsl-n100 volumio[1061]: info: ControllerMpd::stop Apr 23 21:49:16 smsl-n100 volumio[1061]: verbose: ControllerMpd::sendMpdCommand stop Apr 23 21:49:16 smsl-n100 volumio[1061]: info: sendMpdCommand stop took 42 milliseconds Apr 23 21:49:16 smsl-n100 volumio[1061]: info: CoreStateMachine::play index undefined Apr 23 21:49:16 smsl-n100 volumio[1061]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 23 21:49:16 smsl-n100 volumio[1061]: info: CorePlayQueue::getTrack 0 Apr 23 21:49:16 smsl-n100 volumio[1061]: info: CoreStateMachine::startPlaybackTimer Apr 23 21:49:16 smsl-n100 volumio[1061]: info: CorePlayQueue::getTrack 0 Apr 23 21:49:16 smsl-n100 volumio[1061]: info: [1745459356317] ControllerQobuz::clearAddPlayTrack Apr 23 21:49:16 smsl-n100 volumio[1061]: verbose: ControllerMpd::sendMpdCommand stop Apr 23 21:49:16 smsl-n100 volumio[1061]: info: sendMpdCommand stop took 3 milliseconds Apr 23 21:49:16 smsl-n100 volumio[1061]: verbose: ControllerMpd::sendMpdCommand clear Apr 23 21:49:16 smsl-n100 volumio[1061]: info: Apr 23 21:49:16 smsl-n100 volumio[1061]: ---------------------------- MPD announces system playlist update Apr 23 21:49:16 smsl-n100 volumio[1061]: info: Ignoring MPD Status Update Apr 23 21:49:16 smsl-n100 volumio[1061]: info: sendMpdCommand clear took 4 milliseconds Apr 23 21:49:16 smsl-n100 volumio[1061]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/61807819" Apr 23 21:49:16 smsl-n100 volumio[1061]: error: updateQueue error: null Apr 23 21:49:16 smsl-n100 volumio[1061]: info: ------------------------------ 8ms Apr 23 21:49:16 smsl-n100 volumio[1061]: STREAMING PROXY: Handling url /?data=qobuz://song/61807819 Apr 23 21:49:16 smsl-n100 volumio[1061]: info: Executing endpoint getStreamUrlqobuz Apr 23 21:49:16 smsl-n100 volumio[1061]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Apr 23 21:49:16 smsl-n100 volumio[1061]: info: CoreStateMachine::pushState Apr 23 21:49:16 smsl-n100 volumio[1061]: info: CorePlayQueue::getTrack 0 Apr 23 21:49:16 smsl-n100 volumio[1061]: info: CoreCommandRouter::volumioPushState Apr 23 21:49:16 smsl-n100 volumio[1061]: info: MRS: Pushing multiroomSync output update for this device Apr 23 21:49:16 smsl-n100 volumio[1061]: info: MRS: Pushing multiroomSync output Apr 23 21:49:16 smsl-n100 volumio[1061]: error: Serial API: Failed to decode command: 100, message: undefined Apr 23 21:49:16 smsl-n100 volumio[1061]: error: Serial API: Failed to decode command: MAXVOL, message: Apr 23 21:49:16 smsl-n100 volumio[1061]: error: Serial API: Failed to decode command: 100, message: undefined Apr 23 21:49:16 smsl-n100 volumio[1061]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Apr 23 21:49:16 smsl-n100 volumio[1061]: info: getStreamUrl took 292 milliseconds Apr 23 21:49:16 smsl-n100 volumio[1061]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=3184561&eid=61807819&fmt=27&profile=raw&app_id=539451548&cid=2277248&etsp=1745462956&hmac=jEmi_8_ZeF0Z_xkvnIEjJ3pdvHQ Apr 23 21:49:16 smsl-n100 volumio[1061]: STREAMING PROXY: Response: 200, length: 153433782 Apr 23 21:49:16 smsl-n100 volumio[1061]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/61807819" Apr 23 21:49:16 smsl-n100 volumio[1061]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/61807819" took 2 milliseconds Apr 23 21:49:16 smsl-n100 volumio[1061]: STREAMING PROXY: Client dropped request, destroying Apr 23 21:49:16 smsl-n100 volumio[1061]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 23 21:49:16 smsl-n100 volumio[1061]: verbose: ControllerMpd::sendMpdCommand play Apr 23 21:49:16 smsl-n100 volumio[1061]: info: Apr 23 21:49:16 smsl-n100 volumio[1061]: ---------------------------- MPD announces system playlist update Apr 23 21:49:16 smsl-n100 volumio[1061]: info: Ignoring MPD Status Update Apr 23 21:49:16 smsl-n100 volumio[1061]: info: sendMpdCommand play took 8 milliseconds Apr 23 21:49:16 smsl-n100 volumio[1061]: STREAMING PROXY: Handling url /?data=qobuz://song/61807819 Apr 23 21:49:16 smsl-n100 volumio[1061]: info: ------------------------------ 8ms Apr 23 21:49:16 smsl-n100 volumio[1061]: info: Executing endpoint getStreamUrlqobuz Apr 23 21:49:16 smsl-n100 volumio[1061]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Apr 23 21:49:17 smsl-n100 volumio[1061]: info: getStreamUrl took 1147 milliseconds Apr 23 21:49:17 smsl-n100 volumio[1061]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=3184561&eid=61807819&fmt=27&profile=raw&app_id=539451548&cid=2277248&etsp=1745462957&hmac=BoGmGEJNfKCHEM8lFpkxjoICVe8 Apr 23 21:49:18 smsl-n100 volumio[1061]: STREAMING PROXY: Response: 200, length: 153433782 Apr 23 21:49:18 smsl-n100 volumio[1061]: info: Apr 23 21:49:18 smsl-n100 volumio[1061]: ---------------------------- MPD announces state update: player Apr 23 21:49:18 smsl-n100 volumio[1061]: info: ControllerMpd::getState Apr 23 21:49:18 smsl-n100 volumio[1061]: verbose: ControllerMpd::sendMpdCommand status Apr 23 21:49:18 smsl-n100 volumio[1061]: info: sendMpdCommand status took 5 milliseconds Apr 23 21:49:18 smsl-n100 volumio[1061]: verbose: ControllerMpd::parseState Apr 23 21:49:18 smsl-n100 volumio[1061]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 23 21:49:18 smsl-n100 volumio[1061]: info: Apr 23 21:49:18 smsl-n100 volumio[1061]: ---------------------------- MPD announces state update: player Apr 23 21:49:18 smsl-n100 volumio[1061]: info: ControllerMpd::getState Apr 23 21:49:18 smsl-n100 volumio[1061]: verbose: ControllerMpd::sendMpdCommand status Apr 23 21:49:18 smsl-n100 volumio[1061]: info: sendMpdCommand playlistinfo took 11 milliseconds Apr 23 21:49:18 smsl-n100 volumio[1061]: verbose: ControllerMpd::parseTrackInfo Apr 23 21:49:18 smsl-n100 volumio[1061]: info: ControllerMpd::pushState Apr 23 21:49:18 smsl-n100 volumio[1061]: info: CoreCommandRouter::servicePushState Apr 23 21:49:18 smsl-n100 volumio[1061]: info: CorePlayQueue::getTrack 0 Apr 23 21:49:18 smsl-n100 volumio[1061]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":234,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"61807819","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/61807819","trackType":"qobuz"} Apr 23 21:49:18 smsl-n100 volumio[1061]: verbose: CURRENT POSITION 0 Apr 23 21:49:18 smsl-n100 volumio[1061]: info: CoreStateMachine::syncState stateService play Apr 23 21:49:18 smsl-n100 volumio[1061]: info: CoreStateMachine::syncState currentStatus stop Apr 23 21:49:18 smsl-n100 volumio[1061]: info: ------------------------------ 41ms Apr 23 21:49:18 smsl-n100 volumio[1061]: info: sendMpdCommand status took 33 milliseconds Apr 23 21:49:18 smsl-n100 volumio[1061]: verbose: ControllerMpd::parseState Apr 23 21:49:18 smsl-n100 volumio[1061]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 23 21:49:18 smsl-n100 volumio[1061]: info: sendMpdCommand playlistinfo took 26 milliseconds Apr 23 21:49:18 smsl-n100 volumio[1061]: verbose: ControllerMpd::parseTrackInfo Apr 23 21:49:18 smsl-n100 volumio[1061]: info: ControllerMpd::pushState Apr 23 21:49:18 smsl-n100 volumio[1061]: info: CoreCommandRouter::servicePushState Apr 23 21:49:18 smsl-n100 volumio[1061]: info: CorePlayQueue::getTrack 0 Apr 23 21:49:18 smsl-n100 volumio[1061]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":234,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"61807819","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/61807819","trackType":"qobuz"} Apr 23 21:49:18 smsl-n100 volumio[1061]: verbose: CURRENT POSITION 0 Apr 23 21:49:18 smsl-n100 volumio[1061]: info: CoreStateMachine::syncState stateService play Apr 23 21:49:18 smsl-n100 volumio[1061]: info: CoreStateMachine::syncState currentStatus play Apr 23 21:49:18 smsl-n100 volumio[1061]: info: Received an update from plugin. extracting info from payload Apr 23 21:49:18 smsl-n100 volumio[1061]: info: CoreStateMachine::pushState Apr 23 21:49:18 smsl-n100 volumio[1061]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 23 21:49:18 smsl-n100 volumio[1061]: info: CoreCommandRouter::volumioPushState Apr 23 21:49:18 smsl-n100 volumio[1061]: info: MRS: Pushing multiroomSync output update for this device Apr 23 21:49:18 smsl-n100 volumio[1061]: info: MRS: Pushing multiroomSync output Apr 23 21:49:18 smsl-n100 volumio[1061]: info: CoreStateMachine::pushState Apr 23 21:49:18 smsl-n100 volumio[1061]: info: CoreCommandRouter::volumioPushState Apr 23 21:49:18 smsl-n100 volumio[1061]: info: MRS: Pushing multiroomSync output update for this device Apr 23 21:49:18 smsl-n100 volumio[1061]: info: MRS: Pushing multiroomSync output Apr 23 21:49:18 smsl-n100 volumio[1061]: info: ------------------------------ 255ms Apr 23 21:49:18 smsl-n100 volumio[1061]: info: CoreStateMachine::pushState Apr 23 21:49:18 smsl-n100 volumio[1061]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 23 21:49:18 smsl-n100 volumio[1061]: info: CoreCommandRouter::volumioPushState Apr 23 21:49:18 smsl-n100 volumio[1061]: info: MRS: Pushing multiroomSync output update for this device Apr 23 21:49:18 smsl-n100 volumio[1061]: info: MRS: Pushing multiroomSync output Apr 23 21:49:18 smsl-n100 volumio[1061]: error: Serial API: Failed to decode command: 100, message: undefined Apr 23 21:49:18 smsl-n100 volumio[1061]: error: Serial API: Failed to decode command: MAXVOL, message: Apr 23 21:49:18 smsl-n100 volumio[1061]: error: Serial API: Failed to decode command: 100, message: undefined Apr 23 21:49:18 smsl-n100 volumio[1061]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Apr 23 21:49:23 smsl-n100 volumio[1061]: info: CoreCommandRouter::volumioPlay Apr 23 21:49:23 smsl-n100 volumio[1061]: info: CoreStateMachine::play index 0 Apr 23 21:49:23 smsl-n100 volumio[1061]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 23 21:49:23 smsl-n100 volumio[1061]: info: CoreStateMachine::stop Apr 23 21:49:23 smsl-n100 volumio[1061]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 23 21:49:23 smsl-n100 volumio[1061]: info: CoreStateMachine::stPlaybackTimer Apr 23 21:49:23 smsl-n100 volumio[1061]: info: CoreStateMachine::updateTrackBlock Apr 23 21:49:23 smsl-n100 volumio[1061]: info: CorePlayQueue::getTrackBlock Apr 23 21:49:23 smsl-n100 volumio[1061]: info: CoreStateMachine::pushState Apr 23 21:49:23 smsl-n100 volumio[1061]: info: CorePlayQueue::getTrack 0 Apr 23 21:49:23 smsl-n100 volumio[1061]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 23 21:49:23 smsl-n100 volumio[1061]: info: CoreCommandRouter::volumioPushState Apr 23 21:49:23 smsl-n100 volumio[1061]: info: MRS: Pushing multiroomSync output update for this device Apr 23 21:49:23 smsl-n100 volumio[1061]: info: MRS: Pushing multiroomSync output Apr 23 21:49:23 smsl-n100 volumio[1061]: info: CoreStateMachine::serviceStop Apr 23 21:49:23 smsl-n100 volumio[1061]: info: CorePlayQueue::getTrack 0 Apr 23 21:49:23 smsl-n100 volumio[1061]: info: CoreCommandRouter::serviceStop Apr 23 21:49:23 smsl-n100 volumio[1061]: info: [1745459363811] ControllerQobuz::stop Apr 23 21:49:23 smsl-n100 volumio[1061]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 23 21:49:23 smsl-n100 volumio[1061]: info: ControllerMpd::stop Apr 23 21:49:23 smsl-n100 volumio[1061]: verbose: ControllerMpd::sendMpdCommand stop Apr 23 21:49:23 smsl-n100 volumio[1061]: STREAMING PROXY: Client dropped request, destroying Apr 23 21:49:23 smsl-n100 volumio[1061]: info: Apr 23 21:49:23 smsl-n100 volumio[1061]: ---------------------------- MPD announces state update: player Apr 23 21:49:23 smsl-n100 volumio[1061]: info: sendMpdCommand stop took 49 milliseconds Apr 23 21:49:23 smsl-n100 volumio[1061]: info: ControllerMpd::getState Apr 23 21:49:23 smsl-n100 volumio[1061]: verbose: ControllerMpd::sendMpdCommand status Apr 23 21:49:23 smsl-n100 volumio[1061]: info: CoreStateMachine::play index undefined Apr 23 21:49:23 smsl-n100 volumio[1061]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 23 21:49:23 smsl-n100 volumio[1061]: info: CorePlayQueue::getTrack 0 Apr 23 21:49:23 smsl-n100 volumio[1061]: info: CoreStateMachine::startPlaybackTimer Apr 23 21:49:23 smsl-n100 volumio[1061]: info: CorePlayQueue::getTrack 0 Apr 23 21:49:23 smsl-n100 volumio[1061]: info: [1745459363871] ControllerQobuz::clearAddPlayTrack Apr 23 21:49:23 smsl-n100 volumio[1061]: verbose: ControllerMpd::sendMpdCommand stop Apr 23 21:49:23 smsl-n100 volumio[1061]: info: sendMpdCommand status took 9 milliseconds Apr 23 21:49:23 smsl-n100 volumio[1061]: info: sendMpdCommand stop took 3 milliseconds Apr 23 21:49:23 smsl-n100 volumio[1061]: verbose: ControllerMpd::parseState Apr 23 21:49:23 smsl-n100 volumio[1061]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 23 21:49:23 smsl-n100 volumio[1061]: verbose: ControllerMpd::sendMpdCommand clear Apr 23 21:49:23 smsl-n100 volumio[1061]: info: Apr 23 21:49:23 smsl-n100 volumio[1061]: ---------------------------- MPD announces system playlist update Apr 23 21:49:23 smsl-n100 volumio[1061]: info: Ignoring MPD Status Update Apr 23 21:49:23 smsl-n100 volumio[1061]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 23 21:49:23 smsl-n100 volumio[1061]: info: sendMpdCommand clear took 6 milliseconds Apr 23 21:49:23 smsl-n100 volumio[1061]: verbose: ControllerMpd::parseTrackInfo Apr 23 21:49:23 smsl-n100 volumio[1061]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/61807819" Apr 23 21:49:23 smsl-n100 volumio[1061]: info: ControllerMpd::pushState Apr 23 21:49:23 smsl-n100 volumio[1061]: info: CoreCommandRouter::servicePushState Apr 23 21:49:23 smsl-n100 volumio[1061]: info: CoreStateMachine::pushState Apr 23 21:49:23 smsl-n100 volumio[1061]: info: CorePlayQueue::getTrack 0 Apr 23 21:49:23 smsl-n100 volumio[1061]: info: CoreCommandRouter::volumioPushState Apr 23 21:49:23 smsl-n100 volumio[1061]: info: MRS: Pushing multiroomSync output update for this device Apr 23 21:49:23 smsl-n100 volumio[1061]: info: MRS: Pushing multiroomSync output Apr 23 21:49:23 smsl-n100 volumio[1061]: info: CorePlayQueue::getTrack 0 Apr 23 21:49:23 smsl-n100 volumio[1061]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received mpd Apr 23 21:49:23 smsl-n100 volumio[1061]: STREAMING PROXY: Handling url /?data=qobuz://song/61807819 Apr 23 21:49:23 smsl-n100 volumio[1061]: info: ------------------------------ 73ms Apr 23 21:49:23 smsl-n100 volumio[1061]: error: updateQueue error: null Apr 23 21:49:23 smsl-n100 volumio[1061]: info: ------------------------------ 61ms Apr 23 21:49:23 smsl-n100 volumio[1061]: info: CoreStateMachine::pushState Apr 23 21:49:23 smsl-n100 volumio[1061]: info: CorePlayQueue::getTrack 0 Apr 23 21:49:23 smsl-n100 volumio[1061]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 23 21:49:23 smsl-n100 volumio[1061]: info: CoreCommandRouter::volumioPushState Apr 23 21:49:23 smsl-n100 volumio[1061]: info: MRS: Pushing multiroomSync output update for this device Apr 23 21:49:23 smsl-n100 volumio[1061]: info: MRS: Pushing multiroomSync output Apr 23 21:49:23 smsl-n100 volumio[1061]: error: Serial API: Failed to decode command: 100, message: undefined Apr 23 21:49:23 smsl-n100 volumio[1061]: error: Serial API: Failed to decode command: MAXVOL, message: Apr 23 21:49:23 smsl-n100 volumio[1061]: error: Serial API: Failed to decode command: 100, message: undefined Apr 23 21:49:23 smsl-n100 volumio[1061]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Apr 23 21:49:24 smsl-n100 volumio[1061]: info: Executing endpoint getStreamUrlqobuz Apr 23 21:49:24 smsl-n100 volumio[1061]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Apr 23 21:49:24 smsl-n100 volumio[1061]: info: getStreamUrl took 263 milliseconds Apr 23 21:49:24 smsl-n100 volumio[1061]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=3184561&eid=61807819&fmt=27&profile=raw&app_id=539451548&cid=2277248&etsp=1745462964&hmac=xwM-bzf-Xt91m2rEO2u66IZs-dY Apr 23 21:49:24 smsl-n100 volumio[1061]: STREAMING PROXY: Response: 200, length: 153433782 Apr 23 21:49:24 smsl-n100 volumio[1061]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/61807819" Apr 23 21:49:24 smsl-n100 volumio[1061]: STREAMING PROXY: Client dropped request, destroying Apr 23 21:49:24 smsl-n100 volumio[1061]: info: Apr 23 21:49:24 smsl-n100 volumio[1061]: ---------------------------- MPD announces system playlist update Apr 23 21:49:24 smsl-n100 volumio[1061]: info: Ignoring MPD Status Update Apr 23 21:49:24 smsl-n100 volumio[1061]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/61807819" took 5 milliseconds Apr 23 21:49:24 smsl-n100 volumio[1061]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 23 21:49:24 smsl-n100 volumio[1061]: verbose: ControllerMpd::sendMpdCommand play Apr 23 21:49:24 smsl-n100 volumio[1061]: info: ------------------------------ 10ms Apr 23 21:49:24 smsl-n100 volumio[1061]: STREAMING PROXY: Handling url /?data=qobuz://song/61807819 Apr 23 21:49:24 smsl-n100 volumio[1061]: info: sendMpdCommand play took 6 milliseconds Apr 23 21:49:24 smsl-n100 volumio[1061]: info: Executing endpoint getStreamUrlqobuz Apr 23 21:49:24 smsl-n100 volumio[1061]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Apr 23 21:49:24 smsl-n100 volumio[1061]: info: getStreamUrl took 260 milliseconds Apr 23 21:49:24 smsl-n100 volumio[1061]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=3184561&eid=61807819&fmt=27&profile=raw&app_id=539451548&cid=2277248&etsp=1745462964&hmac=xwM-bzf-Xt91m2rEO2u66IZs-dY Apr 23 21:49:24 smsl-n100 volumio[1061]: STREAMING PROXY: Response: 200, length: 153433782 Apr 23 21:49:24 smsl-n100 volumio[1061]: info: Apr 23 21:49:24 smsl-n100 volumio[1061]: ---------------------------- MPD announces state update: player Apr 23 21:49:24 smsl-n100 volumio[1061]: info: ControllerMpd::getState Apr 23 21:49:24 smsl-n100 volumio[1061]: verbose: ControllerMpd::sendMpdCommand status Apr 23 21:49:24 smsl-n100 volumio[1061]: info: Apr 23 21:49:24 smsl-n100 volumio[1061]: ---------------------------- MPD announces state update: player Apr 23 21:49:24 smsl-n100 volumio[1061]: info: sendMpdCommand status took 6 milliseconds Apr 23 21:49:24 smsl-n100 volumio[1061]: info: ControllerMpd::getState Apr 23 21:49:24 smsl-n100 volumio[1061]: verbose: ControllerMpd::sendMpdCommand status Apr 23 21:49:24 smsl-n100 volumio[1061]: verbose: ControllerMpd::parseState Apr 23 21:49:24 smsl-n100 volumio[1061]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 23 21:49:24 smsl-n100 volumio[1061]: info: sendMpdCommand status took 10 milliseconds Apr 23 21:49:24 smsl-n100 volumio[1061]: info: sendMpdCommand playlistinfo took 8 milliseconds Apr 23 21:49:24 smsl-n100 volumio[1061]: verbose: ControllerMpd::parseState Apr 23 21:49:24 smsl-n100 volumio[1061]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 23 21:49:24 smsl-n100 volumio[1061]: verbose: ControllerMpd::parseTrackInfo Apr 23 21:49:24 smsl-n100 volumio[1061]: info: ControllerMpd::pushState Apr 23 21:49:24 smsl-n100 volumio[1061]: info: CoreCommandRouter::servicePushState Apr 23 21:49:24 smsl-n100 volumio[1061]: info: CorePlayQueue::getTrack 0 Apr 23 21:49:24 smsl-n100 volumio[1061]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":234,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"61807819","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/61807819","trackType":"qobuz"} Apr 23 21:49:24 smsl-n100 volumio[1061]: verbose: CURRENT POSITION 0 Apr 23 21:49:24 smsl-n100 volumio[1061]: info: CoreStateMachine::syncState stateService play Apr 23 21:49:24 smsl-n100 volumio[1061]: info: CoreStateMachine::syncState currentStatus stop Apr 23 21:49:24 smsl-n100 volumio[1061]: info: ------------------------------ 40ms Apr 23 21:49:24 smsl-n100 volumio[1061]: info: sendMpdCommand playlistinfo took 18 milliseconds Apr 23 21:49:24 smsl-n100 volumio[1061]: verbose: ControllerMpd::parseTrackInfo Apr 23 21:49:24 smsl-n100 volumio[1061]: info: ControllerMpd::pushState Apr 23 21:49:24 smsl-n100 volumio[1061]: info: CoreCommandRouter::servicePushState Apr 23 21:49:24 smsl-n100 volumio[1061]: info: CorePlayQueue::getTrack 0 Apr 23 21:49:24 smsl-n100 volumio[1061]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":234,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"61807819","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/61807819","trackType":"qobuz"} Apr 23 21:49:24 smsl-n100 volumio[1061]: verbose: CURRENT POSITION 0 Apr 23 21:49:24 smsl-n100 volumio[1061]: info: CoreStateMachine::syncState stateService play Apr 23 21:49:24 smsl-n100 volumio[1061]: info: CoreStateMachine::syncState currentStatus play Apr 23 21:49:24 smsl-n100 volumio[1061]: info: Received an update from plugin. extracting info from payload Apr 23 21:49:24 smsl-n100 volumio[1061]: info: CoreStateMachine::pushState Apr 23 21:49:24 smsl-n100 volumio[1061]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 23 21:49:24 smsl-n100 volumio[1061]: info: CoreCommandRouter::volumioPushState Apr 23 21:49:24 smsl-n100 volumio[1061]: info: MRS: Pushing multiroomSync output update for this device Apr 23 21:49:24 smsl-n100 volumio[1061]: info: MRS: Pushing multiroomSync output Apr 23 21:49:24 smsl-n100 volumio[1061]: info: CoreStateMachine::pushState Apr 23 21:49:24 smsl-n100 volumio[1061]: info: CoreCommandRouter::volumioPushState Apr 23 21:49:24 smsl-n100 volumio[1061]: info: MRS: Pushing multiroomSync output update for this device Apr 23 21:49:24 smsl-n100 volumio[1061]: info: MRS: Pushing multiroomSync output Apr 23 21:49:24 smsl-n100 volumio[1061]: info: ------------------------------ 178ms Apr 23 21:49:25 smsl-n100 volumio[1061]: info: CoreStateMachine::pushState Apr 23 21:49:25 smsl-n100 volumio[1061]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 23 21:49:25 smsl-n100 volumio[1061]: info: CoreCommandRouter::volumioPushState Apr 23 21:49:25 smsl-n100 volumio[1061]: info: MRS: Pushing multiroomSync output update for this device Apr 23 21:49:25 smsl-n100 volumio[1061]: info: MRS: Pushing multiroomSync output Apr 23 21:49:25 smsl-n100 volumio[1061]: error: Serial API: Failed to decode command: 100, message: undefined Apr 23 21:49:25 smsl-n100 volumio[1061]: error: Serial API: Failed to decode command: MAXVOL, message: Apr 23 21:49:25 smsl-n100 volumio[1061]: error: Serial API: Failed to decode command: 100, message: undefined Apr 23 21:49:25 smsl-n100 volumio[1061]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Apr 23 21:49:25 smsl-n100 volumio[1061]: info: CoreCommandRouter::volumioPrevious Apr 23 21:49:25 smsl-n100 volumio[1061]: info: CoreStateMachine::previous Apr 23 21:49:25 smsl-n100 volumio[1061]: info: [1745459365487] ControllerQobuz::seek Apr 23 21:49:25 smsl-n100 volumio[1061]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 23 21:49:25 smsl-n100 volumio[1061]: info: ControllerMpd::seek Apr 23 21:49:25 smsl-n100 volumio[1061]: STREAMING PROXY: Client dropped request, destroying Apr 23 21:49:25 smsl-n100 volumio[1061]: STREAMING PROXY: Handling url /?data=qobuz://song/61807819 Apr 23 21:49:25 smsl-n100 volumio[1061]: info: Executing endpoint getStreamUrlqobuz Apr 23 21:49:25 smsl-n100 volumio[1061]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Apr 23 21:49:25 smsl-n100 volumio[1061]: info: getStreamUrl took 246 milliseconds Apr 23 21:49:25 smsl-n100 volumio[1061]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=3184561&eid=61807819&fmt=27&profile=raw&app_id=539451548&cid=2277248&etsp=1745462965&hmac=YQbR2iokqMEc34u4AB4pvIjSVfA Apr 23 21:49:25 smsl-n100 volumio[1061]: STREAMING PROXY: Got range request: bytes=478- Apr 23 21:49:25 smsl-n100 volumio[1061]: STREAMING PROXY: Response: 206, length: 153433304 Apr 23 21:49:25 smsl-n100 volumio[1061]: error: null Apr 23 21:49:25 smsl-n100 volumio[1061]: info: Apr 23 21:49:25 smsl-n100 volumio[1061]: ---------------------------- MPD announces state update: player Apr 23 21:49:25 smsl-n100 volumio[1061]: info: ControllerMpd::getState Apr 23 21:49:25 smsl-n100 volumio[1061]: verbose: ControllerMpd::sendMpdCommand status Apr 23 21:49:25 smsl-n100 volumio[1061]: info: sendMpdCommand status took 8 milliseconds Apr 23 21:49:25 smsl-n100 volumio[1061]: verbose: ControllerMpd::parseState Apr 23 21:49:25 smsl-n100 volumio[1061]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 23 21:49:25 smsl-n100 volumio[1061]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 23 21:49:25 smsl-n100 volumio[1061]: verbose: ControllerMpd::parseTrackInfo Apr 23 21:49:25 smsl-n100 volumio[1061]: info: ControllerMpd::pushState Apr 23 21:49:25 smsl-n100 volumio[1061]: info: CoreCommandRouter::servicePushState Apr 23 21:49:25 smsl-n100 volumio[1061]: info: CorePlayQueue::getTrack 0 Apr 23 21:49:25 smsl-n100 volumio[1061]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":234,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5164 Kbps","isStreaming":false,"title":"61807819","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/61807819","trackType":"qobuz"} Apr 23 21:49:25 smsl-n100 volumio[1061]: verbose: CURRENT POSITION 0 Apr 23 21:49:25 smsl-n100 volumio[1061]: info: CoreStateMachine::syncState stateService play Apr 23 21:49:25 smsl-n100 volumio[1061]: info: CoreStateMachine::syncState currentStatus play Apr 23 21:49:25 smsl-n100 volumio[1061]: info: Received an update from plugin. extracting info from payload Apr 23 21:49:25 smsl-n100 volumio[1061]: info: CoreStateMachine::pushState Apr 23 21:49:25 smsl-n100 volumio[1061]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 23 21:49:25 smsl-n100 volumio[1061]: info: CoreCommandRouter::volumioPushState Apr 23 21:49:25 smsl-n100 volumio[1061]: info: MRS: Pushing multiroomSync output update for this device Apr 23 21:49:25 smsl-n100 volumio[1061]: info: MRS: Pushing multiroomSync output Apr 23 21:49:25 smsl-n100 volumio[1061]: info: CoreStateMachine::pushState Apr 23 21:49:25 smsl-n100 volumio[1061]: info: CoreCommandRouter::volumioPushState Apr 23 21:49:26 smsl-n100 volumio[1061]: info: MRS: Pushing multiroomSync output update for this device Apr 23 21:49:26 smsl-n100 volumio[1061]: info: MRS: Pushing multiroomSync output Apr 23 21:49:26 smsl-n100 volumio[1061]: info: ------------------------------ 174ms Apr 23 21:49:42 smsl-n100 volumio[1061]: info: CoreCommandRouter::volumioGetState Apr 23 21:49:44 smsl-n100 volumio[1061]: info: Executing endpoint metavolumio Apr 23 21:49:44 smsl-n100 volumio[1061]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 23 21:49:44 smsl-n100 volumio[1061]: info: Executing endpoint metavolumio Apr 23 21:49:44 smsl-n100 volumio[1061]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 23 21:49:44 smsl-n100 volumio[1061]: info: Executing endpoint metavolumio Apr 23 21:49:44 smsl-n100 volumio[1061]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 23 21:49:44 smsl-n100 volumio[1061]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 23 21:49:44 smsl-n100 volumio[1061]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 23 21:49:44 smsl-n100 volumio[1061]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Apr 23 21:49:44 smsl-n100 sudo[5490]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Apr 23 21:49:44 smsl-n100 sudo[5490]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 21:49:44 smsl-n100 sudo[5490]: pam_unix(sudo:session): session closed for user root Apr 23 21:49:44 smsl-n100 sudo[5496]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Apr 23 21:49:44 smsl-n100 sudo[5496]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 21:49:44 smsl-n100 sudo[5496]: pam_unix(sudo:session): session closed for user root Apr 23 21:49:44 smsl-n100 sudo[5504]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Apr 23 21:49:44 smsl-n100 sudo[5504]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 21:49:44 smsl-n100 sudo[5504]: pam_unix(sudo:session): session closed for user root Apr 23 21:49:44 smsl-n100 sudo[5510]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Apr 23 21:49:44 smsl-n100 sudo[5510]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 21:49:44 smsl-n100 sudo[5510]: pam_unix(sudo:session): session closed for user root Apr 23 21:49:44 smsl-n100 sudo[5516]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 23 21:49:44 smsl-n100 sudo[5516]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 21:49:44 smsl-n100 sudo[5516]: pam_unix(sudo:session): session closed for user root Apr 23 21:49:44 smsl-n100 volumio[1061]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Apr 23 21:49:44 smsl-n100 volumio[1061]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Apr 23 21:49:44 smsl-n100 sudo[5519]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 23 21:49:44 smsl-n100 sudo[5519]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 21:49:44 smsl-n100 sudo[5519]: pam_unix(sudo:session): session closed for user root Apr 23 21:49:44 smsl-n100 sudo[5522]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 23 21:49:44 smsl-n100 sudo[5522]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 21:49:47 smsl-n100 volumio[1061]: error: Failed request for metavolumio API Apr 23 21:49:50 smsl-n100 sudo[5522]: pam_unix(sudo:session): session closed for user root Apr 23 21:50:36 smsl-n100 volumio[1061]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNetworkSettings Apr 23 21:50:36 smsl-n100 volumio[1061]: info: Saving new wireless network Apr 23 21:50:36 smsl-n100 sudo[5752]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/wpa_supplicant/wpa_supplicant.conf Apr 23 21:50:36 smsl-n100 sudo[5752]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 21:50:36 smsl-n100 sudo[5752]: pam_unix(sudo:session): session closed for user root Apr 23 21:50:36 smsl-n100 volumio[1061]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Apr 23 21:50:36 smsl-n100 volumio[1061]: info: Discovery: Restarting Advertising due to device name change Apr 23 21:50:36 smsl-n100 sudo[5757]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Apr 23 21:50:36 smsl-n100 sudo[5757]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 21:50:36 smsl-n100 systemd[1]: Stopping Wireless Services... Apr 23 21:50:36 smsl-n100 wpa_supplicant[849]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Apr 23 21:50:36 smsl-n100 dhcpcd[608]: wlan0: carrier acquired Apr 23 21:50:36 smsl-n100 volumio[1061]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 23 21:50:36 smsl-n100 wpa_supplicant[849]: wlan0: CTRL-EVENT-TERMINATING Apr 23 21:50:36 smsl-n100 systemd[1]: wireless.service: Succeeded. Apr 23 21:50:36 smsl-n100 systemd[1]: Stopped Wireless Services. Apr 23 21:50:36 smsl-n100 dhcpcd[608]: wlan0: IAID 2b:ee:c7:b6 Apr 23 21:50:36 smsl-n100 dhcpcd[608]: wlan0: carrier lost Apr 23 21:50:36 smsl-n100 volumio[1061]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 23 21:50:36 smsl-n100 systemd[1]: Starting Wireless Services... Apr 23 21:50:36 smsl-n100 volumio[1061]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 23 21:50:37 smsl-n100 volumio[1061]: info: Discovery: A device disappeared from network Apr 23 21:50:37 smsl-n100 wireless.js[5761]: Cleaning previous... Apr 23 21:50:38 smsl-n100 sudo[5794]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Apr 23 21:50:38 smsl-n100 sudo[5794]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 21:50:38 smsl-n100 sudo[5794]: pam_unix(sudo:session): session closed for user root Apr 23 21:50:38 smsl-n100 sudo[5796]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Apr 23 21:50:38 smsl-n100 sudo[5796]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 21:50:38 smsl-n100 sudo[5796]: pam_unix(sudo:session): session closed for user root Apr 23 21:50:38 smsl-n100 wireless.js[5761]: Stopped aP Apr 23 21:50:38 smsl-n100 sudo[5803]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Apr 23 21:50:38 smsl-n100 sudo[5803]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 21:50:38 smsl-n100 dhcpcd[608]: wlan0: carrier acquired Apr 23 21:50:38 smsl-n100 sudo[5803]: pam_unix(sudo:session): session closed for user root Apr 23 21:50:38 smsl-n100 dhcpcd[608]: wlan0: IAID 2b:ee:c7:b6 Apr 23 21:50:38 smsl-n100 sudo[5806]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Apr 23 21:50:38 smsl-n100 sudo[5806]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 21:50:38 smsl-n100 sudo[5806]: pam_unix(sudo:session): session closed for user root Apr 23 21:50:38 smsl-n100 sudo[5813]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Apr 23 21:50:38 smsl-n100 sudo[5813]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 21:50:38 smsl-n100 sudo[5813]: pam_unix(sudo:session): session closed for user root Apr 23 21:50:38 smsl-n100 sudo[5815]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Apr 23 21:50:38 smsl-n100 sudo[5815]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 21:50:38 smsl-n100 dhcpcd[608]: wlan0: soliciting an IPv6 router Apr 23 21:50:38 smsl-n100 dhcpcd[608]: wlan0: soliciting a DHCP lease Apr 23 21:50:41 smsl-n100 volumio[1061]: info: Discovery: Started advertising with name: SMSL N100 Apr 23 21:50:41 smsl-n100 volumio[1061]: info: CALLMETHOD: system_controller network saveWirelessNet [object Object] Apr 23 21:50:41 smsl-n100 volumio[1061]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNet Apr 23 21:50:41 smsl-n100 sudo[5843]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/network/interfaces Apr 23 21:50:41 smsl-n100 sudo[5843]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 21:50:41 smsl-n100 sudo[5843]: pam_unix(sudo:session): session closed for user root Apr 23 21:50:42 smsl-n100 sudo[5845]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/dhcpcd.conf Apr 23 21:50:42 smsl-n100 sudo[5845]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 21:50:42 smsl-n100 sudo[5845]: pam_unix(sudo:session): session closed for user root Apr 23 21:50:42 smsl-n100 volumio[1061]: info: Permissions for /etc/network/interfaces set Apr 23 21:50:42 smsl-n100 volumio[1061]: info: Restarting Wireless Network Apr 23 21:50:42 smsl-n100 volumio[1061]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Apr 23 21:50:42 smsl-n100 volumio[1061]: info: Discovery: Restarting Advertising due to device name change Apr 23 21:50:42 smsl-n100 sudo[5849]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Apr 23 21:50:42 smsl-n100 sudo[5849]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 21:50:42 smsl-n100 volumio[1061]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 23 21:50:42 smsl-n100 volumio[1061]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 23 21:50:42 smsl-n100 systemd[1]: wireless.service: Control process exited, code=killed, status=15/TERM Apr 23 21:50:42 smsl-n100 systemd[1]: wireless.service: Killing process 5812 (sh) with signal SIGKILL. Apr 23 21:50:42 smsl-n100 systemd[1]: wireless.service: Killing process 5815 (sudo) with signal SIGKILL. Apr 23 21:50:42 smsl-n100 systemd[1]: wireless.service: Killing process 5816 (grep) with signal SIGKILL. Apr 23 21:50:42 smsl-n100 systemd[1]: wireless.service: Killing process 5817 (cut) with signal SIGKILL. Apr 23 21:50:42 smsl-n100 systemd[1]: wireless.service: Killing process 5818 (iw) with signal SIGKILL. Apr 23 21:50:42 smsl-n100 systemd[1]: wireless.service: Succeeded. Apr 23 21:50:42 smsl-n100 systemd[1]: Stopped Wireless Services. Apr 23 21:50:42 smsl-n100 volumio[1061]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 23 21:50:42 smsl-n100 systemd[1]: Starting Wireless Services... Apr 23 21:50:42 smsl-n100 volumio[1061]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 23 21:50:43 smsl-n100 wireless.js[5851]: Cleaning previous... Apr 23 21:50:43 smsl-n100 sudo[5867]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Apr 23 21:50:43 smsl-n100 sudo[5867]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 21:50:43 smsl-n100 sudo[5867]: pam_unix(sudo:session): session closed for user root Apr 23 21:50:43 smsl-n100 sudo[5869]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Apr 23 21:50:43 smsl-n100 sudo[5869]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 21:50:43 smsl-n100 dhcpcd[608]: wlan0: carrier lost Apr 23 21:50:43 smsl-n100 sudo[5869]: pam_unix(sudo:session): session closed for user root Apr 23 21:50:43 smsl-n100 wireless.js[5851]: Stopped aP Apr 23 21:50:43 smsl-n100 sudo[5906]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Apr 23 21:50:43 smsl-n100 sudo[5906]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 21:50:43 smsl-n100 sudo[5906]: pam_unix(sudo:session): session closed for user root Apr 23 21:50:43 smsl-n100 sudo[5908]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Apr 23 21:50:43 smsl-n100 sudo[5908]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 21:50:43 smsl-n100 sudo[5908]: pam_unix(sudo:session): session closed for user root Apr 23 21:50:43 smsl-n100 sudo[5915]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Apr 23 21:50:43 smsl-n100 sudo[5915]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 21:50:43 smsl-n100 sudo[5915]: pam_unix(sudo:session): session closed for user root Apr 23 21:50:43 smsl-n100 sudo[5919]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Apr 23 21:50:43 smsl-n100 sudo[5919]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 21:50:44 smsl-n100 volumio[1061]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Apr 23 21:50:44 smsl-n100 volumio[1061]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Apr 23 21:50:44 smsl-n100 dhcpcd[608]: wlan0: carrier acquired Apr 23 21:50:44 smsl-n100 dhcpcd[608]: wlan0: confile file changed, expiring leases Apr 23 21:50:44 smsl-n100 dhcpcd[608]: wlan0: IAID 2b:ee:c7:b6 Apr 23 21:50:44 smsl-n100 sudo[5924]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 23 21:50:44 smsl-n100 sudo[5924]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 21:50:45 smsl-n100 dhcpcd[608]: wlan0: soliciting a DHCP lease Apr 23 21:50:45 smsl-n100 dhcpcd[608]: wlan0: soliciting an IPv6 router Apr 23 21:50:47 smsl-n100 volumio[1061]: info: Discovery: Started advertising with name: SMSL N100 Apr 23 21:50:48 smsl-n100 volumio[1061]: info: Discovery: adding 3d5d3895-8fd3-4353-ae6d-b643143101bf Apr 23 21:50:48 smsl-n100 volumio[1061]: info: Discovery: Found device SMSL N100 Apr 23 21:50:48 smsl-n100 volumio[1061]: info: CoreCommandRouter::volumioGetState Apr 23 21:50:48 smsl-n100 volumio[1061]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 23 21:50:48 smsl-n100 volumio[1061]: Error: The value NaN is not a number Apr 23 21:50:48 smsl-n100 volumio[1061]: at Config.forceToType (/volumio/node_modules/v-conf/index.js:322:20) Apr 23 21:50:48 smsl-n100 volumio[1061]: at Config.set (/volumio/node_modules/v-conf/index.js:153:25) Apr 23 21:50:48 smsl-n100 volumio[1061]: at ControllerVolumioDiscovery.updateMultiroomDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:323:25) Apr 23 21:50:48 smsl-n100 volumio[1061]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:273:10) Apr 23 21:50:48 smsl-n100 volumio[1061]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12) Apr 23 21:50:48 smsl-n100 volumio[1061]: at Browser.emit (events.js:400:28) Apr 23 21:50:48 smsl-n100 volumio[1061]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) Apr 23 21:50:48 smsl-n100 volumio[1061]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) Apr 23 21:50:48 smsl-n100 volumio[1061]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5) Apr 23 21:50:48 smsl-n100 volumio[1061]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21) Apr 23 21:50:48 smsl-n100 volumio[1061]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 Apr 23 21:50:48 smsl-n100 volumio[1061]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7) Apr 23 21:50:48 smsl-n100 volumio[1061]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) Apr 23 21:50:48 smsl-n100 volumio[1061]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 23 21:50:49 smsl-n100 sudo[5949]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-23 21:49 Apr 23 21:50:49 smsl-n100 sudo[5949]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 21:50:49 smsl-n100 sudo[5919]: pam_unix(sudo:session): session closed for user root 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"