-- Logs begin at Tue 2026-02-10 11:48:31 CET, end at Tue 2026-02-10 14:28:41 CET. -- Feb 10 14:27:00 smsl-n100 volumio[805]: info: sendMpdCommand clearerror took 51 milliseconds Feb 10 14:27:00 smsl-n100 volumio[805]: info: sendMpdCommand playlistinfo took 44 milliseconds Feb 10 14:27:00 smsl-n100 volumio[805]: info: sendMpdCommand playlistinfo took 44 milliseconds Feb 10 14:27:00 smsl-n100 volumio[805]: info: sendMpdCommand playlistinfo took 42 milliseconds Feb 10 14:27:00 smsl-n100 volumio[805]: info: sendMpdCommand playlistinfo took 41 milliseconds Feb 10 14:27:00 smsl-n100 volumio[805]: info: sendMpdCommand status took 16 milliseconds Feb 10 14:27:00 smsl-n100 volumio[805]: verbose: ControllerMpd::parseTrackInfo Feb 10 14:27:00 smsl-n100 volumio[805]: verbose: ControllerMpd::parseTrackInfo Feb 10 14:27:00 smsl-n100 volumio[805]: verbose: ControllerMpd::parseTrackInfo Feb 10 14:27:00 smsl-n100 volumio[805]: verbose: ControllerMpd::parseTrackInfo Feb 10 14:27:00 smsl-n100 volumio[805]: verbose: ControllerMpd::parseState Feb 10 14:27:00 smsl-n100 volumio[805]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 14:27:00 smsl-n100 volumio[805]: info: ControllerMpd::pushState Feb 10 14:27:00 smsl-n100 volumio[805]: info: CoreCommandRouter::servicePushState Feb 10 14:27:00 smsl-n100 volumio[805]: verbose: In UPNP mode Feb 10 14:27:00 smsl-n100 volumio[805]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":12174,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"foobar2000 audio stream","artist":null,"album":null,"uri":"http://192.168.1.129:49885/37/stream.wav","trackType":"wav"} Feb 10 14:27:00 smsl-n100 volumio[805]: verbose: CURRENT POSITION 0 Feb 10 14:27:00 smsl-n100 volumio[805]: info: CoreStateMachine::syncState stateService pause Feb 10 14:27:00 smsl-n100 volumio[805]: info: CoreStateMachine::syncState currentStatus stop Feb 10 14:27:00 smsl-n100 volumio[805]: info: CoreStateMachine::pushState Feb 10 14:27:00 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushState Feb 10 14:27:00 smsl-n100 volumio[805]: info: ControllerMpd::pushState Feb 10 14:27:00 smsl-n100 volumio[805]: info: CoreCommandRouter::servicePushState Feb 10 14:27:00 smsl-n100 volumio[805]: verbose: In UPNP mode Feb 10 14:27:00 smsl-n100 volumio[805]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":12174,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"foobar2000 audio stream","artist":null,"album":null,"uri":"http://192.168.1.129:49885/37/stream.wav","trackType":"wav"} Feb 10 14:27:00 smsl-n100 volumio[805]: verbose: CURRENT POSITION 0 Feb 10 14:27:00 smsl-n100 volumio[805]: info: CoreStateMachine::syncState stateService pause Feb 10 14:27:00 smsl-n100 volumio[805]: info: CoreStateMachine::syncState currentStatus stop Feb 10 14:27:00 smsl-n100 volumio[805]: info: CoreStateMachine::pushState Feb 10 14:27:00 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushState Feb 10 14:27:00 smsl-n100 volumio[805]: info: ControllerMpd::pushState Feb 10 14:27:00 smsl-n100 volumio[805]: info: CoreCommandRouter::servicePushState Feb 10 14:27:00 smsl-n100 volumio[805]: verbose: In UPNP mode Feb 10 14:27:00 smsl-n100 volumio[805]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":12174,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"foobar2000 audio stream","artist":null,"album":null,"uri":"http://192.168.1.129:49885/37/stream.wav","trackType":"wav"} Feb 10 14:27:00 smsl-n100 volumio[805]: verbose: CURRENT POSITION 0 Feb 10 14:27:00 smsl-n100 volumio[805]: info: CoreStateMachine::syncState stateService pause Feb 10 14:27:00 smsl-n100 volumio[805]: info: CoreStateMachine::syncState currentStatus stop Feb 10 14:27:00 smsl-n100 volumio[805]: info: CoreStateMachine::pushState Feb 10 14:27:00 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushState Feb 10 14:27:00 smsl-n100 volumio[805]: info: ControllerMpd::pushState Feb 10 14:27:00 smsl-n100 volumio[805]: info: CoreCommandRouter::servicePushState Feb 10 14:27:00 smsl-n100 volumio[805]: verbose: In UPNP mode Feb 10 14:27:00 smsl-n100 volumio[805]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":12174,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"foobar2000 audio stream","artist":null,"album":null,"uri":"http://192.168.1.129:49885/37/stream.wav","trackType":"wav"} Feb 10 14:27:00 smsl-n100 volumio[805]: verbose: CURRENT POSITION 0 Feb 10 14:27:00 smsl-n100 volumio[805]: info: CoreStateMachine::syncState stateService pause Feb 10 14:27:00 smsl-n100 volumio[805]: info: CoreStateMachine::syncState currentStatus stop Feb 10 14:27:00 smsl-n100 volumio[805]: info: CoreStateMachine::pushState Feb 10 14:27:00 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushState Feb 10 14:27:00 smsl-n100 volumio[805]: info: ------------------------------ 138ms Feb 10 14:27:00 smsl-n100 volumio[805]: info: ------------------------------ 134ms Feb 10 14:27:00 smsl-n100 volumio[805]: info: ------------------------------ 119ms Feb 10 14:27:00 smsl-n100 volumio[805]: info: ------------------------------ 117ms Feb 10 14:27:00 smsl-n100 volumio[805]: info: sendMpdCommand playlistinfo took 51 milliseconds Feb 10 14:27:00 smsl-n100 volumio[805]: verbose: ControllerMpd::parseTrackInfo Feb 10 14:27:00 smsl-n100 volumio[805]: info: ControllerMpd::pushState Feb 10 14:27:00 smsl-n100 volumio[805]: info: CoreCommandRouter::servicePushState Feb 10 14:27:00 smsl-n100 volumio[805]: verbose: In UPNP mode Feb 10 14:27:00 smsl-n100 volumio[805]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":12174,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"foobar2000 audio stream","artist":null,"album":null,"uri":"http://192.168.1.129:49885/37/stream.wav","trackType":"wav"} Feb 10 14:27:00 smsl-n100 volumio[805]: verbose: CURRENT POSITION 0 Feb 10 14:27:00 smsl-n100 volumio[805]: info: CoreStateMachine::syncState stateService pause Feb 10 14:27:00 smsl-n100 volumio[805]: info: CoreStateMachine::syncState currentStatus stop Feb 10 14:27:00 smsl-n100 volumio[805]: info: CoreStateMachine::pushState Feb 10 14:27:00 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushState Feb 10 14:27:00 smsl-n100 volumio[805]: info: ------------------------------ 89ms Feb 10 14:27:00 smsl-n100 volumio[805]: info: CoreStateMachine::pushState Feb 10 14:27:00 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushState Feb 10 14:27:00 smsl-n100 volumio[805]: error: Serial API: Failed to decode command: 100, message: undefined Feb 10 14:27:00 smsl-n100 volumio[805]: error: Serial API: Failed to decode command: MAXVOL, message: Feb 10 14:27:00 smsl-n100 volumio[805]: error: Serial API: Failed to decode command: 100, message: undefined Feb 10 14:27:00 smsl-n100 volumio[805]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Feb 10 14:27:00 smsl-n100 volumio[805]: info: Feb 10 14:27:00 smsl-n100 volumio[805]: ---------------------------- MPD announces state update: player Feb 10 14:27:00 smsl-n100 volumio[805]: info: ControllerMpd::getState Feb 10 14:27:00 smsl-n100 volumio[805]: verbose: ControllerMpd::sendMpdCommand status Feb 10 14:27:00 smsl-n100 volumio[805]: info: Feb 10 14:27:00 smsl-n100 volumio[805]: ---------------------------- MPD announces state update: player Feb 10 14:27:00 smsl-n100 volumio[805]: info: ControllerMpd::getState Feb 10 14:27:00 smsl-n100 volumio[805]: verbose: ControllerMpd::sendMpdCommand status Feb 10 14:27:00 smsl-n100 volumio[805]: info: Feb 10 14:27:00 smsl-n100 volumio[805]: ---------------------------- MPD announces state update: player Feb 10 14:27:00 smsl-n100 volumio[805]: info: ControllerMpd::getState Feb 10 14:27:00 smsl-n100 volumio[805]: verbose: ControllerMpd::sendMpdCommand status Feb 10 14:27:00 smsl-n100 volumio[805]: verbose: ControllerMpd::sendMpdCommand clearerror Feb 10 14:27:00 smsl-n100 volumio[805]: info: sendMpdCommand status took 13 milliseconds Feb 10 14:27:00 smsl-n100 volumio[805]: verbose: ControllerMpd::sendMpdCommand clearerror Feb 10 14:27:00 smsl-n100 volumio[805]: info: sendMpdCommand status took 12 milliseconds Feb 10 14:27:00 smsl-n100 volumio[805]: verbose: ControllerMpd::sendMpdCommand clearerror Feb 10 14:27:00 smsl-n100 volumio[805]: info: sendMpdCommand status took 11 milliseconds Feb 10 14:27:00 smsl-n100 volumio[805]: verbose: ControllerMpd::parseState Feb 10 14:27:00 smsl-n100 volumio[805]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 14:27:00 smsl-n100 volumio[805]: verbose: ControllerMpd::parseState Feb 10 14:27:00 smsl-n100 volumio[805]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 14:27:00 smsl-n100 volumio[805]: verbose: ControllerMpd::parseState Feb 10 14:27:00 smsl-n100 volumio[805]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 14:27:00 smsl-n100 volumio[805]: info: sendMpdCommand clearerror took 17 milliseconds Feb 10 14:27:00 smsl-n100 volumio[805]: info: sendMpdCommand clearerror took 16 milliseconds Feb 10 14:27:00 smsl-n100 volumio[805]: info: sendMpdCommand clearerror took 14 milliseconds Feb 10 14:27:00 smsl-n100 volumio[805]: info: sendMpdCommand playlistinfo took 9 milliseconds Feb 10 14:27:00 smsl-n100 volumio[805]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 10 14:27:00 smsl-n100 volumio[805]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 10 14:27:00 smsl-n100 volumio[805]: verbose: ControllerMpd::parseTrackInfo Feb 10 14:27:00 smsl-n100 volumio[805]: verbose: ControllerMpd::parseTrackInfo Feb 10 14:27:00 smsl-n100 volumio[805]: verbose: ControllerMpd::parseTrackInfo Feb 10 14:27:00 smsl-n100 volumio[805]: info: ControllerMpd::pushState Feb 10 14:27:00 smsl-n100 volumio[805]: info: CoreCommandRouter::servicePushState Feb 10 14:27:00 smsl-n100 volumio[805]: verbose: In UPNP mode Feb 10 14:27:00 smsl-n100 volumio[805]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":12174,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"foobar2000 audio stream","artist":null,"album":null,"uri":"http://192.168.1.129:49885/37/stream.wav","trackType":"wav"} Feb 10 14:27:00 smsl-n100 volumio[805]: verbose: CURRENT POSITION 0 Feb 10 14:27:00 smsl-n100 volumio[805]: info: CoreStateMachine::syncState stateService pause Feb 10 14:27:00 smsl-n100 volumio[805]: info: CoreStateMachine::syncState currentStatus stop Feb 10 14:27:00 smsl-n100 volumio[805]: info: CoreStateMachine::pushState Feb 10 14:27:00 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushState Feb 10 14:27:00 smsl-n100 volumio[805]: info: ControllerMpd::pushState Feb 10 14:27:00 smsl-n100 volumio[805]: info: CoreCommandRouter::servicePushState Feb 10 14:27:00 smsl-n100 volumio[805]: verbose: In UPNP mode Feb 10 14:27:00 smsl-n100 volumio[805]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":12174,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"foobar2000 audio stream","artist":null,"album":null,"uri":"http://192.168.1.129:49885/37/stream.wav","trackType":"wav"} Feb 10 14:27:00 smsl-n100 volumio[805]: verbose: CURRENT POSITION 0 Feb 10 14:27:00 smsl-n100 volumio[805]: info: CoreStateMachine::syncState stateService pause Feb 10 14:27:00 smsl-n100 volumio[805]: info: CoreStateMachine::syncState currentStatus stop Feb 10 14:27:00 smsl-n100 volumio[805]: info: CoreStateMachine::pushState Feb 10 14:27:00 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushState Feb 10 14:27:00 smsl-n100 volumio[805]: info: ControllerMpd::pushState Feb 10 14:27:00 smsl-n100 volumio[805]: info: CoreCommandRouter::servicePushState Feb 10 14:27:00 smsl-n100 volumio[805]: verbose: In UPNP mode Feb 10 14:27:00 smsl-n100 volumio[805]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":12174,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"foobar2000 audio stream","artist":null,"album":null,"uri":"http://192.168.1.129:49885/37/stream.wav","trackType":"wav"} Feb 10 14:27:00 smsl-n100 volumio[805]: verbose: CURRENT POSITION 0 Feb 10 14:27:00 smsl-n100 volumio[805]: info: CoreStateMachine::syncState stateService pause Feb 10 14:27:00 smsl-n100 volumio[805]: info: CoreStateMachine::syncState currentStatus stop Feb 10 14:27:00 smsl-n100 volumio[805]: info: CoreStateMachine::pushState Feb 10 14:27:00 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushState Feb 10 14:27:00 smsl-n100 volumio[805]: info: ------------------------------ 69ms Feb 10 14:27:00 smsl-n100 volumio[805]: info: ------------------------------ 67ms Feb 10 14:27:00 smsl-n100 volumio[805]: info: ------------------------------ 65ms Feb 10 14:27:02 smsl-n100 volumio[805]: info: Clearing queue after UPNP request Feb 10 14:27:02 smsl-n100 volumio[805]: info: CoreStateMachine::ClearQueue Feb 10 14:27:02 smsl-n100 volumio[805]: info: CoreStateMachine::stop Feb 10 14:27:02 smsl-n100 volumio[805]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 10 14:27:02 smsl-n100 volumio[805]: info: CorePlayQueue::clearPlayQueue Feb 10 14:27:02 smsl-n100 volumio[805]: info: CorePlayQueue::saveQueue Feb 10 14:27:02 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushState Feb 10 14:27:02 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushQueue Feb 10 14:27:02 smsl-n100 volumio[805]: info: Feb 10 14:27:02 smsl-n100 volumio[805]: ---------------------------- MPD announces system playlist update Feb 10 14:27:02 smsl-n100 volumio[805]: info: Ignoring MPD Status Update Feb 10 14:27:02 smsl-n100 volumio[805]: info: Feb 10 14:27:02 smsl-n100 volumio[805]: ---------------------------- MPD announces state update: player Feb 10 14:27:02 smsl-n100 volumio[805]: info: ControllerMpd::getState Feb 10 14:27:02 smsl-n100 volumio[805]: verbose: ControllerMpd::sendMpdCommand status Feb 10 14:27:02 smsl-n100 volumio[805]: info: Feb 10 14:27:02 smsl-n100 volumio[805]: ---------------------------- MPD announces system playlist update Feb 10 14:27:02 smsl-n100 volumio[805]: info: Ignoring MPD Status Update Feb 10 14:27:02 smsl-n100 volumio[805]: info: Feb 10 14:27:02 smsl-n100 volumio[805]: ---------------------------- MPD announces state update: player Feb 10 14:27:02 smsl-n100 volumio[805]: info: ControllerMpd::getState Feb 10 14:27:02 smsl-n100 volumio[805]: verbose: ControllerMpd::sendMpdCommand status Feb 10 14:27:02 smsl-n100 volumio[805]: info: Feb 10 14:27:02 smsl-n100 volumio[805]: ---------------------------- MPD announces system playlist update Feb 10 14:27:02 smsl-n100 volumio[805]: info: Ignoring MPD Status Update Feb 10 14:27:02 smsl-n100 volumio[805]: info: Feb 10 14:27:02 smsl-n100 volumio[805]: ---------------------------- MPD announces state update: player Feb 10 14:27:02 smsl-n100 volumio[805]: info: ControllerMpd::getState Feb 10 14:27:02 smsl-n100 volumio[805]: verbose: ControllerMpd::sendMpdCommand status Feb 10 14:27:02 smsl-n100 volumio[805]: error: updateQueue error: null Feb 10 14:27:02 smsl-n100 volumio[805]: error: updateQueue error: null Feb 10 14:27:02 smsl-n100 volumio[805]: error: updateQueue error: null Feb 10 14:27:02 smsl-n100 volumio[805]: info: ------------------------------ 16ms Feb 10 14:27:02 smsl-n100 volumio[805]: info: sendMpdCommand status took 14 milliseconds Feb 10 14:27:02 smsl-n100 volumio[805]: info: ------------------------------ 14ms Feb 10 14:27:02 smsl-n100 volumio[805]: info: sendMpdCommand status took 11 milliseconds Feb 10 14:27:02 smsl-n100 volumio[805]: info: ------------------------------ 10ms Feb 10 14:27:02 smsl-n100 volumio[805]: info: sendMpdCommand status took 8 milliseconds Feb 10 14:27:02 smsl-n100 volumio[805]: verbose: ControllerMpd::parseState Feb 10 14:27:02 smsl-n100 volumio[805]: verbose: ControllerMpd::parseState Feb 10 14:27:02 smsl-n100 volumio[805]: verbose: ControllerMpd::parseState Feb 10 14:27:02 smsl-n100 volumio[805]: info: ControllerMpd::pushState Feb 10 14:27:02 smsl-n100 volumio[805]: info: CoreCommandRouter::servicePushState Feb 10 14:27:02 smsl-n100 volumio[805]: info: CoreStateMachine::pushState Feb 10 14:27:02 smsl-n100 volumio[805]: info: CorePlayQueue::getTrack 0 Feb 10 14:27:02 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 14:27:02 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushState Feb 10 14:27:02 smsl-n100 volumio[805]: info: CorePlayQueue::getTrack 0 Feb 10 14:27:02 smsl-n100 volumio[805]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 10 14:27:02 smsl-n100 volumio[805]: verbose: CURRENT POSITION 0 Feb 10 14:27:02 smsl-n100 volumio[805]: info: CoreStateMachine::syncState stateService stop Feb 10 14:27:02 smsl-n100 volumio[805]: info: CoreStateMachine::syncState currentStatus stop Feb 10 14:27:02 smsl-n100 volumio[805]: info: CoreStateMachine::pushState Feb 10 14:27:02 smsl-n100 volumio[805]: info: CorePlayQueue::getTrack 0 Feb 10 14:27:02 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushState Feb 10 14:27:02 smsl-n100 volumio[805]: info: No code Feb 10 14:27:02 smsl-n100 volumio[805]: info: CoreStateMachine::pushState Feb 10 14:27:02 smsl-n100 volumio[805]: info: CorePlayQueue::getTrack 0 Feb 10 14:27:02 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushState Feb 10 14:27:02 smsl-n100 volumio[805]: info: ControllerMpd::pushState Feb 10 14:27:02 smsl-n100 volumio[805]: info: CoreCommandRouter::servicePushState Feb 10 14:27:02 smsl-n100 volumio[805]: info: CoreStateMachine::pushState Feb 10 14:27:02 smsl-n100 volumio[805]: info: CorePlayQueue::getTrack 0 Feb 10 14:27:02 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushState Feb 10 14:27:02 smsl-n100 volumio[805]: info: CorePlayQueue::getTrack 0 Feb 10 14:27:02 smsl-n100 volumio[805]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 10 14:27:02 smsl-n100 volumio[805]: verbose: CURRENT POSITION 0 Feb 10 14:27:02 smsl-n100 volumio[805]: info: CoreStateMachine::syncState stateService stop Feb 10 14:27:02 smsl-n100 volumio[805]: info: CoreStateMachine::syncState currentStatus stop Feb 10 14:27:02 smsl-n100 volumio[805]: info: CoreStateMachine::pushState Feb 10 14:27:02 smsl-n100 volumio[805]: info: CorePlayQueue::getTrack 0 Feb 10 14:27:02 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushState Feb 10 14:27:02 smsl-n100 volumio[805]: info: No code Feb 10 14:27:02 smsl-n100 volumio[805]: info: CoreStateMachine::pushState Feb 10 14:27:02 smsl-n100 volumio[805]: info: CorePlayQueue::getTrack 0 Feb 10 14:27:02 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushState Feb 10 14:27:02 smsl-n100 volumio[805]: info: ControllerMpd::pushState Feb 10 14:27:02 smsl-n100 volumio[805]: info: CoreCommandRouter::servicePushState Feb 10 14:27:02 smsl-n100 volumio[805]: info: CoreStateMachine::pushState Feb 10 14:27:02 smsl-n100 volumio[805]: info: CorePlayQueue::getTrack 0 Feb 10 14:27:02 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushState Feb 10 14:27:02 smsl-n100 volumio[805]: info: CorePlayQueue::getTrack 0 Feb 10 14:27:02 smsl-n100 volumio[805]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 10 14:27:02 smsl-n100 volumio[805]: verbose: CURRENT POSITION 0 Feb 10 14:27:02 smsl-n100 volumio[805]: info: CoreStateMachine::syncState stateService stop Feb 10 14:27:02 smsl-n100 volumio[805]: info: CoreStateMachine::syncState currentStatus stop Feb 10 14:27:02 smsl-n100 volumio[805]: info: CoreStateMachine::pushState Feb 10 14:27:02 smsl-n100 volumio[805]: info: CorePlayQueue::getTrack 0 Feb 10 14:27:02 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushState Feb 10 14:27:02 smsl-n100 volumio[805]: info: No code Feb 10 14:27:02 smsl-n100 volumio[805]: info: CoreStateMachine::pushState Feb 10 14:27:02 smsl-n100 volumio[805]: info: CorePlayQueue::getTrack 0 Feb 10 14:27:02 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushState Feb 10 14:27:02 smsl-n100 volumio[805]: info: ------------------------------ 86ms Feb 10 14:27:02 smsl-n100 volumio[805]: info: ------------------------------ 84ms Feb 10 14:27:02 smsl-n100 volumio[805]: info: ------------------------------ 81ms Feb 10 14:27:02 smsl-n100 volumio[805]: info: Clearing queue after UPNP request Feb 10 14:27:02 smsl-n100 volumio[805]: info: CoreStateMachine::pushState Feb 10 14:27:02 smsl-n100 volumio[805]: info: CorePlayQueue::getTrack 0 Feb 10 14:27:02 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushState Feb 10 14:27:02 smsl-n100 volumio[805]: error: Serial API: Failed to decode command: 100, message: undefined Feb 10 14:27:02 smsl-n100 volumio[805]: error: Serial API: Failed to decode command: MAXVOL, message: Feb 10 14:27:02 smsl-n100 volumio[805]: error: Serial API: Failed to decode command: 100, message: undefined Feb 10 14:27:02 smsl-n100 volumio[805]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Feb 10 14:27:03 smsl-n100 volumio[805]: info: CoreStateMachine::ClearQueue Feb 10 14:27:03 smsl-n100 volumio[805]: info: CoreStateMachine::stop Feb 10 14:27:03 smsl-n100 volumio[805]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 10 14:27:03 smsl-n100 volumio[805]: info: CorePlayQueue::clearPlayQueue Feb 10 14:27:03 smsl-n100 volumio[805]: info: CorePlayQueue::saveQueue Feb 10 14:27:03 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushState Feb 10 14:27:03 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushQueue Feb 10 14:27:03 smsl-n100 volumio[805]: info: Feb 10 14:27:03 smsl-n100 volumio[805]: ---------------------------- MPD announces system playlist update Feb 10 14:27:03 smsl-n100 volumio[805]: info: Ignoring MPD Status Update Feb 10 14:27:03 smsl-n100 volumio[805]: info: Feb 10 14:27:03 smsl-n100 volumio[805]: ---------------------------- MPD announces system playlist update Feb 10 14:27:03 smsl-n100 volumio[805]: info: Ignoring MPD Status Update Feb 10 14:27:03 smsl-n100 volumio[805]: info: Feb 10 14:27:03 smsl-n100 volumio[805]: ---------------------------- MPD announces system playlist update Feb 10 14:27:03 smsl-n100 volumio[805]: info: Ignoring MPD Status Update Feb 10 14:27:03 smsl-n100 volumio[805]: error: updateQueue error: null Feb 10 14:27:03 smsl-n100 volumio[805]: error: updateQueue error: null Feb 10 14:27:03 smsl-n100 volumio[805]: error: updateQueue error: null Feb 10 14:27:03 smsl-n100 volumio[805]: info: ------------------------------ 11ms Feb 10 14:27:03 smsl-n100 volumio[805]: info: ------------------------------ 9ms Feb 10 14:27:03 smsl-n100 volumio[805]: info: ------------------------------ 6ms Feb 10 14:27:03 smsl-n100 volumio[805]: info: Starting UPNP Playback Feb 10 14:27:03 smsl-n100 volumio[805]: info: Preparing playback through UPNP Feb 10 14:27:03 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioGetState Feb 10 14:27:03 smsl-n100 volumio[805]: info: CorePlayQueue::getTrack 0 Feb 10 14:27:03 smsl-n100 volumio[805]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 10 14:27:03 smsl-n100 volumio[805]: info: Feb 10 14:27:03 smsl-n100 volumio[805]: ---------------------------- MPD announces system playlist update Feb 10 14:27:03 smsl-n100 volumio[805]: info: Ignoring MPD Status Update Feb 10 14:27:03 smsl-n100 volumio[805]: info: Feb 10 14:27:03 smsl-n100 volumio[805]: ---------------------------- MPD announces system playlist update Feb 10 14:27:03 smsl-n100 volumio[805]: info: Ignoring MPD Status Update Feb 10 14:27:03 smsl-n100 volumio[805]: info: Feb 10 14:27:03 smsl-n100 volumio[805]: ---------------------------- MPD announces system playlist update Feb 10 14:27:03 smsl-n100 volumio[805]: info: Ignoring MPD Status Update Feb 10 14:27:03 smsl-n100 volumio[805]: info: ------------------------------ 8ms Feb 10 14:27:03 smsl-n100 volumio[805]: info: ------------------------------ 6ms Feb 10 14:27:03 smsl-n100 volumio[805]: info: ------------------------------ 4ms Feb 10 14:27:03 smsl-n100 volumio[805]: info: Feb 10 14:27:03 smsl-n100 volumio[805]: ---------------------------- MPD announces system playlist update Feb 10 14:27:03 smsl-n100 volumio[805]: info: Ignoring MPD Status Update Feb 10 14:27:03 smsl-n100 volumio[805]: info: Feb 10 14:27:03 smsl-n100 volumio[805]: ---------------------------- MPD announces system playlist update Feb 10 14:27:03 smsl-n100 volumio[805]: info: Ignoring MPD Status Update Feb 10 14:27:03 smsl-n100 volumio[805]: info: Feb 10 14:27:03 smsl-n100 volumio[805]: ---------------------------- MPD announces system playlist update Feb 10 14:27:03 smsl-n100 volumio[805]: info: Ignoring MPD Status Update Feb 10 14:27:03 smsl-n100 volumio[805]: info: Feb 10 14:27:03 smsl-n100 volumio[805]: ---------------------------- MPD announces system playlist update Feb 10 14:27:03 smsl-n100 volumio[805]: info: Ignoring MPD Status Update Feb 10 14:27:03 smsl-n100 volumio[805]: info: ------------------------------ 10ms Feb 10 14:27:03 smsl-n100 volumio[805]: info: ------------------------------ 8ms Feb 10 14:27:03 smsl-n100 volumio[805]: info: ------------------------------ 5ms Feb 10 14:27:03 smsl-n100 volumio[805]: info: ------------------------------ 5ms Feb 10 14:27:03 smsl-n100 volumio[805]: info: Feb 10 14:27:03 smsl-n100 volumio[805]: ---------------------------- MPD announces system playlist update Feb 10 14:27:03 smsl-n100 volumio[805]: info: Ignoring MPD Status Update Feb 10 14:27:03 smsl-n100 volumio[805]: info: Feb 10 14:27:03 smsl-n100 volumio[805]: ---------------------------- MPD announces system playlist update Feb 10 14:27:03 smsl-n100 volumio[805]: info: Ignoring MPD Status Update Feb 10 14:27:03 smsl-n100 volumio[805]: info: Feb 10 14:27:03 smsl-n100 volumio[805]: ---------------------------- MPD announces system playlist update Feb 10 14:27:03 smsl-n100 volumio[805]: info: Ignoring MPD Status Update Feb 10 14:27:03 smsl-n100 volumio[805]: info: ------------------------------ 7ms Feb 10 14:27:03 smsl-n100 volumio[805]: info: ------------------------------ 6ms Feb 10 14:27:03 smsl-n100 volumio[805]: info: ------------------------------ 5ms Feb 10 14:27:03 smsl-n100 volumio[805]: info: Feb 10 14:27:03 smsl-n100 volumio[805]: ---------------------------- MPD announces system playlist update Feb 10 14:27:03 smsl-n100 volumio[805]: info: Ignoring MPD Status Update Feb 10 14:27:03 smsl-n100 volumio[805]: info: Feb 10 14:27:03 smsl-n100 volumio[805]: ---------------------------- MPD announces system playlist update Feb 10 14:27:03 smsl-n100 volumio[805]: info: Ignoring MPD Status Update Feb 10 14:27:03 smsl-n100 volumio[805]: info: Feb 10 14:27:03 smsl-n100 volumio[805]: ---------------------------- MPD announces system playlist update Feb 10 14:27:03 smsl-n100 volumio[805]: info: Ignoring MPD Status Update Feb 10 14:27:03 smsl-n100 volumio[805]: info: ------------------------------ 7ms Feb 10 14:27:03 smsl-n100 volumio[805]: info: ------------------------------ 5ms Feb 10 14:27:03 smsl-n100 volumio[805]: info: ------------------------------ 4ms Feb 10 14:27:03 smsl-n100 volumio[805]: info: Feb 10 14:27:03 smsl-n100 volumio[805]: ---------------------------- MPD announces system playlist update Feb 10 14:27:03 smsl-n100 volumio[805]: info: Ignoring MPD Status Update Feb 10 14:27:03 smsl-n100 volumio[805]: info: Feb 10 14:27:03 smsl-n100 volumio[805]: ---------------------------- MPD announces system playlist update Feb 10 14:27:03 smsl-n100 volumio[805]: info: Ignoring MPD Status Update Feb 10 14:27:03 smsl-n100 volumio[805]: info: Feb 10 14:27:03 smsl-n100 volumio[805]: ---------------------------- MPD announces system playlist update Feb 10 14:27:03 smsl-n100 volumio[805]: info: Ignoring MPD Status Update Feb 10 14:27:03 smsl-n100 volumio[805]: info: ------------------------------ 8ms Feb 10 14:27:03 smsl-n100 volumio[805]: info: ------------------------------ 5ms Feb 10 14:27:03 smsl-n100 volumio[805]: info: ------------------------------ 5ms Feb 10 14:27:03 smsl-n100 volumio[805]: info: Feb 10 14:27:03 smsl-n100 volumio[805]: ---------------------------- MPD announces system playlist update Feb 10 14:27:03 smsl-n100 volumio[805]: info: Ignoring MPD Status Update Feb 10 14:27:03 smsl-n100 volumio[805]: info: Feb 10 14:27:03 smsl-n100 volumio[805]: ---------------------------- MPD announces system playlist update Feb 10 14:27:03 smsl-n100 volumio[805]: info: Ignoring MPD Status Update Feb 10 14:27:03 smsl-n100 volumio[805]: info: ------------------------------ 6ms Feb 10 14:27:03 smsl-n100 volumio[805]: info: ------------------------------ 5ms Feb 10 14:27:03 smsl-n100 volumio[805]: info: Clearing queue after UPNP request Feb 10 14:27:04 smsl-n100 volumio[805]: info: CoreStateMachine::ClearQueue Feb 10 14:27:04 smsl-n100 volumio[805]: info: CoreStateMachine::stop Feb 10 14:27:04 smsl-n100 volumio[805]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 10 14:27:04 smsl-n100 volumio[805]: info: CorePlayQueue::clearPlayQueue Feb 10 14:27:04 smsl-n100 volumio[805]: info: CorePlayQueue::saveQueue Feb 10 14:27:04 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushState Feb 10 14:27:04 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushQueue Feb 10 14:27:04 smsl-n100 volumio[805]: info: Feb 10 14:27:04 smsl-n100 volumio[805]: ---------------------------- MPD announces system playlist update Feb 10 14:27:04 smsl-n100 volumio[805]: info: Ignoring MPD Status Update Feb 10 14:27:04 smsl-n100 volumio[805]: info: Feb 10 14:27:04 smsl-n100 volumio[805]: ---------------------------- MPD announces system playlist update Feb 10 14:27:04 smsl-n100 volumio[805]: info: Ignoring MPD Status Update Feb 10 14:27:04 smsl-n100 volumio[805]: info: Feb 10 14:27:04 smsl-n100 volumio[805]: ---------------------------- MPD announces system playlist update Feb 10 14:27:04 smsl-n100 volumio[805]: info: Ignoring MPD Status Update Feb 10 14:27:04 smsl-n100 volumio[805]: error: updateQueue error: null Feb 10 14:27:04 smsl-n100 volumio[805]: error: updateQueue error: null Feb 10 14:27:04 smsl-n100 volumio[805]: error: updateQueue error: null Feb 10 14:27:04 smsl-n100 volumio[805]: info: ------------------------------ 9ms Feb 10 14:27:04 smsl-n100 volumio[805]: info: ------------------------------ 7ms Feb 10 14:27:04 smsl-n100 volumio[805]: info: ------------------------------ 5ms Feb 10 14:27:04 smsl-n100 volumio[805]: info: Clearing queue after UPNP request Feb 10 14:27:04 smsl-n100 volumio[805]: info: CoreStateMachine::ClearQueue Feb 10 14:27:04 smsl-n100 volumio[805]: info: CoreStateMachine::stop Feb 10 14:27:04 smsl-n100 volumio[805]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 10 14:27:04 smsl-n100 volumio[805]: info: CorePlayQueue::clearPlayQueue Feb 10 14:27:04 smsl-n100 volumio[805]: info: CorePlayQueue::saveQueue Feb 10 14:27:04 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushState Feb 10 14:27:04 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushQueue Feb 10 14:27:04 smsl-n100 volumio[805]: info: Feb 10 14:27:04 smsl-n100 volumio[805]: ---------------------------- MPD announces system playlist update Feb 10 14:27:04 smsl-n100 volumio[805]: info: Ignoring MPD Status Update Feb 10 14:27:04 smsl-n100 volumio[805]: info: Feb 10 14:27:04 smsl-n100 volumio[805]: ---------------------------- MPD announces system playlist update Feb 10 14:27:04 smsl-n100 volumio[805]: info: Ignoring MPD Status Update Feb 10 14:27:04 smsl-n100 volumio[805]: info: Feb 10 14:27:04 smsl-n100 volumio[805]: ---------------------------- MPD announces system playlist update Feb 10 14:27:04 smsl-n100 volumio[805]: info: Ignoring MPD Status Update Feb 10 14:27:04 smsl-n100 volumio[805]: error: updateQueue error: null Feb 10 14:27:04 smsl-n100 volumio[805]: error: updateQueue error: null Feb 10 14:27:04 smsl-n100 volumio[805]: error: updateQueue error: null Feb 10 14:27:04 smsl-n100 volumio[805]: info: ------------------------------ 10ms Feb 10 14:27:04 smsl-n100 volumio[805]: info: ------------------------------ 7ms Feb 10 14:27:04 smsl-n100 volumio[805]: info: ------------------------------ 5ms Feb 10 14:27:04 smsl-n100 volumio[805]: info: Starting UPNP Playback Feb 10 14:27:04 smsl-n100 volumio[805]: info: Preparing playback through UPNP Feb 10 14:27:04 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioGetState Feb 10 14:27:04 smsl-n100 volumio[805]: info: CorePlayQueue::getTrack 0 Feb 10 14:27:04 smsl-n100 volumio[805]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 10 14:27:04 smsl-n100 volumio[805]: info: Feb 10 14:27:04 smsl-n100 volumio[805]: ---------------------------- MPD announces system playlist update Feb 10 14:27:04 smsl-n100 volumio[805]: info: Ignoring MPD Status Update Feb 10 14:27:04 smsl-n100 volumio[805]: info: Feb 10 14:27:04 smsl-n100 volumio[805]: ---------------------------- MPD announces system playlist update Feb 10 14:27:04 smsl-n100 volumio[805]: info: Ignoring MPD Status Update Feb 10 14:27:04 smsl-n100 volumio[805]: info: Feb 10 14:27:04 smsl-n100 volumio[805]: ---------------------------- MPD announces system playlist update Feb 10 14:27:04 smsl-n100 volumio[805]: info: Ignoring MPD Status Update Feb 10 14:27:04 smsl-n100 volumio[805]: info: Feb 10 14:27:04 smsl-n100 volumio[805]: ---------------------------- MPD announces system playlist update Feb 10 14:27:04 smsl-n100 volumio[805]: info: Ignoring MPD Status Update Feb 10 14:27:04 smsl-n100 volumio[805]: info: ------------------------------ 10ms Feb 10 14:27:04 smsl-n100 volumio[805]: info: ------------------------------ 8ms Feb 10 14:27:04 smsl-n100 volumio[805]: info: ------------------------------ 6ms Feb 10 14:27:04 smsl-n100 volumio[805]: info: ------------------------------ 5ms Feb 10 14:27:04 smsl-n100 volumio[805]: info: Feb 10 14:27:04 smsl-n100 volumio[805]: ---------------------------- MPD announces system playlist update Feb 10 14:27:04 smsl-n100 volumio[805]: info: Ignoring MPD Status Update Feb 10 14:27:04 smsl-n100 volumio[805]: info: Feb 10 14:27:04 smsl-n100 volumio[805]: ---------------------------- MPD announces system playlist update Feb 10 14:27:04 smsl-n100 volumio[805]: info: Ignoring MPD Status Update Feb 10 14:27:04 smsl-n100 volumio[805]: info: Feb 10 14:27:04 smsl-n100 volumio[805]: ---------------------------- MPD announces system playlist update Feb 10 14:27:04 smsl-n100 volumio[805]: info: Ignoring MPD Status Update Feb 10 14:27:04 smsl-n100 volumio[805]: info: ------------------------------ 7ms Feb 10 14:27:04 smsl-n100 volumio[805]: info: ------------------------------ 6ms Feb 10 14:27:04 smsl-n100 volumio[805]: info: ------------------------------ 5ms Feb 10 14:27:04 smsl-n100 volumio[805]: info: Feb 10 14:27:04 smsl-n100 volumio[805]: ---------------------------- MPD announces system playlist update Feb 10 14:27:04 smsl-n100 volumio[805]: info: Ignoring MPD Status Update Feb 10 14:27:04 smsl-n100 volumio[805]: info: Feb 10 14:27:04 smsl-n100 volumio[805]: ---------------------------- MPD announces system playlist update Feb 10 14:27:04 smsl-n100 volumio[805]: info: Ignoring MPD Status Update Feb 10 14:27:04 smsl-n100 volumio[805]: info: ------------------------------ 6ms Feb 10 14:27:04 smsl-n100 volumio[805]: info: ------------------------------ 4ms Feb 10 14:27:04 smsl-n100 volumio[805]: info: Feb 10 14:27:04 smsl-n100 volumio[805]: ---------------------------- MPD announces system playlist update Feb 10 14:27:04 smsl-n100 volumio[805]: info: Ignoring MPD Status Update Feb 10 14:27:04 smsl-n100 volumio[805]: info: Feb 10 14:27:04 smsl-n100 volumio[805]: ---------------------------- MPD announces system playlist update Feb 10 14:27:04 smsl-n100 volumio[805]: info: Ignoring MPD Status Update Feb 10 14:27:04 smsl-n100 volumio[805]: info: Feb 10 14:27:04 smsl-n100 volumio[805]: ---------------------------- MPD announces system playlist update Feb 10 14:27:04 smsl-n100 volumio[805]: info: Ignoring MPD Status Update Feb 10 14:27:04 smsl-n100 volumio[805]: info: ------------------------------ 8ms Feb 10 14:27:04 smsl-n100 volumio[805]: info: ------------------------------ 5ms Feb 10 14:27:04 smsl-n100 volumio[805]: info: ------------------------------ 4ms Feb 10 14:27:04 smsl-n100 volumio[805]: info: Feb 10 14:27:04 smsl-n100 volumio[805]: ---------------------------- MPD announces system playlist update Feb 10 14:27:04 smsl-n100 volumio[805]: info: Ignoring MPD Status Update Feb 10 14:27:04 smsl-n100 volumio[805]: info: Feb 10 14:27:04 smsl-n100 volumio[805]: ---------------------------- MPD announces system playlist update Feb 10 14:27:04 smsl-n100 volumio[805]: info: Ignoring MPD Status Update Feb 10 14:27:04 smsl-n100 volumio[805]: info: Feb 10 14:27:04 smsl-n100 volumio[805]: ---------------------------- MPD announces system playlist update Feb 10 14:27:04 smsl-n100 volumio[805]: info: Ignoring MPD Status Update Feb 10 14:27:04 smsl-n100 volumio[805]: info: Feb 10 14:27:04 smsl-n100 volumio[805]: ---------------------------- MPD announces system playlist update Feb 10 14:27:04 smsl-n100 volumio[805]: info: Ignoring MPD Status Update Feb 10 14:27:04 smsl-n100 volumio[805]: info: ------------------------------ 9ms Feb 10 14:27:04 smsl-n100 volumio[805]: info: ------------------------------ 8ms Feb 10 14:27:04 smsl-n100 volumio[805]: info: ------------------------------ 6ms Feb 10 14:27:04 smsl-n100 volumio[805]: info: ------------------------------ 5ms Feb 10 14:27:04 smsl-n100 volumio[805]: info: Feb 10 14:27:04 smsl-n100 volumio[805]: ---------------------------- MPD announces system playlist update Feb 10 14:27:04 smsl-n100 volumio[805]: info: Ignoring MPD Status Update Feb 10 14:27:04 smsl-n100 volumio[805]: info: Feb 10 14:27:04 smsl-n100 volumio[805]: ---------------------------- MPD announces system playlist update Feb 10 14:27:04 smsl-n100 volumio[805]: info: Ignoring MPD Status Update Feb 10 14:27:04 smsl-n100 volumio[805]: info: ------------------------------ 6ms Feb 10 14:27:04 smsl-n100 volumio[805]: info: ------------------------------ 5ms Feb 10 14:27:05 smsl-n100 volumio[805]: info: Feb 10 14:27:05 smsl-n100 volumio[805]: ---------------------------- MPD announces state update: player Feb 10 14:27:05 smsl-n100 volumio[805]: info: ControllerMpd::getState Feb 10 14:27:05 smsl-n100 volumio[805]: verbose: ControllerMpd::sendMpdCommand status Feb 10 14:27:05 smsl-n100 volumio[805]: info: Feb 10 14:27:05 smsl-n100 volumio[805]: ---------------------------- MPD announces state update: player Feb 10 14:27:05 smsl-n100 volumio[805]: info: ControllerMpd::getState Feb 10 14:27:05 smsl-n100 volumio[805]: verbose: ControllerMpd::sendMpdCommand status Feb 10 14:27:05 smsl-n100 volumio[805]: info: Feb 10 14:27:05 smsl-n100 volumio[805]: ---------------------------- MPD announces state update: player Feb 10 14:27:05 smsl-n100 volumio[805]: info: ControllerMpd::getState Feb 10 14:27:05 smsl-n100 volumio[805]: verbose: ControllerMpd::sendMpdCommand status Feb 10 14:27:05 smsl-n100 volumio[805]: info: Feb 10 14:27:05 smsl-n100 volumio[805]: ---------------------------- MPD announces state update: player Feb 10 14:27:05 smsl-n100 volumio[805]: info: ControllerMpd::getState Feb 10 14:27:05 smsl-n100 volumio[805]: verbose: ControllerMpd::sendMpdCommand status Feb 10 14:27:05 smsl-n100 volumio[805]: verbose: ControllerMpd::sendMpdCommand clearerror Feb 10 14:27:05 smsl-n100 volumio[805]: info: sendMpdCommand status took 18 milliseconds Feb 10 14:27:05 smsl-n100 volumio[805]: verbose: ControllerMpd::sendMpdCommand clearerror Feb 10 14:27:05 smsl-n100 volumio[805]: info: sendMpdCommand status took 15 milliseconds Feb 10 14:27:05 smsl-n100 volumio[805]: verbose: ControllerMpd::parseState Feb 10 14:27:05 smsl-n100 volumio[805]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 14:27:05 smsl-n100 volumio[805]: verbose: ControllerMpd::parseState Feb 10 14:27:05 smsl-n100 volumio[805]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 14:27:05 smsl-n100 volumio[805]: info: Feb 10 14:27:05 smsl-n100 volumio[805]: ---------------------------- MPD announces state update: player Feb 10 14:27:05 smsl-n100 volumio[805]: info: ControllerMpd::getState Feb 10 14:27:05 smsl-n100 volumio[805]: verbose: ControllerMpd::sendMpdCommand status Feb 10 14:27:05 smsl-n100 volumio[805]: info: Feb 10 14:27:05 smsl-n100 volumio[805]: ---------------------------- MPD announces state update: player Feb 10 14:27:05 smsl-n100 volumio[805]: info: ControllerMpd::getState Feb 10 14:27:05 smsl-n100 volumio[805]: verbose: ControllerMpd::sendMpdCommand status Feb 10 14:27:05 smsl-n100 volumio[805]: verbose: ControllerMpd::sendMpdCommand clearerror Feb 10 14:27:05 smsl-n100 volumio[805]: info: sendMpdCommand status took 57 milliseconds Feb 10 14:27:05 smsl-n100 volumio[805]: verbose: ControllerMpd::sendMpdCommand clearerror Feb 10 14:27:05 smsl-n100 volumio[805]: info: sendMpdCommand status took 55 milliseconds Feb 10 14:27:05 smsl-n100 volumio[805]: info: sendMpdCommand clearerror took 52 milliseconds Feb 10 14:27:05 smsl-n100 volumio[805]: info: sendMpdCommand clearerror took 50 milliseconds Feb 10 14:27:05 smsl-n100 volumio[805]: info: sendMpdCommand playlistinfo took 37 milliseconds Feb 10 14:27:05 smsl-n100 volumio[805]: info: sendMpdCommand playlistinfo took 36 milliseconds Feb 10 14:27:05 smsl-n100 volumio[805]: info: sendMpdCommand status took 33 milliseconds Feb 10 14:27:05 smsl-n100 volumio[805]: info: sendMpdCommand status took 18 milliseconds Feb 10 14:27:05 smsl-n100 volumio[805]: verbose: ControllerMpd::parseState Feb 10 14:27:05 smsl-n100 volumio[805]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 14:27:05 smsl-n100 volumio[805]: verbose: ControllerMpd::parseState Feb 10 14:27:05 smsl-n100 volumio[805]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 14:27:05 smsl-n100 volumio[805]: verbose: ControllerMpd::parseTrackInfo Feb 10 14:27:05 smsl-n100 volumio[805]: verbose: ControllerMpd::parseTrackInfo Feb 10 14:27:05 smsl-n100 volumio[805]: verbose: ControllerMpd::parseState Feb 10 14:27:05 smsl-n100 volumio[805]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 14:27:05 smsl-n100 volumio[805]: verbose: ControllerMpd::parseState Feb 10 14:27:05 smsl-n100 volumio[805]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 14:27:05 smsl-n100 volumio[805]: info: ControllerMpd::pushState Feb 10 14:27:05 smsl-n100 volumio[805]: info: CoreCommandRouter::servicePushState Feb 10 14:27:05 smsl-n100 volumio[805]: verbose: In UPNP mode Feb 10 14:27:05 smsl-n100 volumio[805]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":12174,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"foobar2000 audio stream","artist":null,"album":null,"uri":"http://192.168.1.129:49885/39/stream.wav","trackType":"wav"} Feb 10 14:27:05 smsl-n100 volumio[805]: verbose: CURRENT POSITION 0 Feb 10 14:27:05 smsl-n100 volumio[805]: info: CoreStateMachine::syncState stateService pause Feb 10 14:27:05 smsl-n100 volumio[805]: info: CoreStateMachine::syncState currentStatus stop Feb 10 14:27:05 smsl-n100 volumio[805]: info: CoreStateMachine::pushState Feb 10 14:27:05 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 14:27:05 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushState Feb 10 14:27:05 smsl-n100 volumio[805]: info: ControllerMpd::pushState Feb 10 14:27:05 smsl-n100 volumio[805]: info: CoreCommandRouter::servicePushState Feb 10 14:27:05 smsl-n100 volumio[805]: verbose: In UPNP mode Feb 10 14:27:05 smsl-n100 volumio[805]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":12174,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"foobar2000 audio stream","artist":null,"album":null,"uri":"http://192.168.1.129:49885/39/stream.wav","trackType":"wav"} Feb 10 14:27:05 smsl-n100 volumio[805]: verbose: CURRENT POSITION 0 Feb 10 14:27:05 smsl-n100 volumio[805]: info: CoreStateMachine::syncState stateService pause Feb 10 14:27:05 smsl-n100 volumio[805]: info: CoreStateMachine::syncState currentStatus stop Feb 10 14:27:05 smsl-n100 volumio[805]: info: CoreStateMachine::pushState Feb 10 14:27:05 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushState Feb 10 14:27:05 smsl-n100 volumio[805]: info: ------------------------------ 112ms Feb 10 14:27:05 smsl-n100 volumio[805]: info: ------------------------------ 108ms Feb 10 14:27:05 smsl-n100 volumio[805]: info: sendMpdCommand clearerror took 54 milliseconds Feb 10 14:27:05 smsl-n100 volumio[805]: info: sendMpdCommand clearerror took 53 milliseconds Feb 10 14:27:05 smsl-n100 volumio[805]: info: sendMpdCommand playlistinfo took 42 milliseconds Feb 10 14:27:05 smsl-n100 volumio[805]: info: sendMpdCommand playlistinfo took 41 milliseconds Feb 10 14:27:05 smsl-n100 volumio[805]: info: sendMpdCommand playlistinfo took 39 milliseconds Feb 10 14:27:05 smsl-n100 volumio[805]: info: sendMpdCommand playlistinfo took 39 milliseconds Feb 10 14:27:05 smsl-n100 volumio[805]: verbose: ControllerMpd::parseTrackInfo Feb 10 14:27:05 smsl-n100 volumio[805]: verbose: ControllerMpd::parseTrackInfo Feb 10 14:27:05 smsl-n100 volumio[805]: verbose: ControllerMpd::parseTrackInfo Feb 10 14:27:05 smsl-n100 volumio[805]: verbose: ControllerMpd::parseTrackInfo Feb 10 14:27:05 smsl-n100 volumio[805]: info: ControllerMpd::pushState Feb 10 14:27:05 smsl-n100 volumio[805]: info: CoreCommandRouter::servicePushState Feb 10 14:27:05 smsl-n100 volumio[805]: verbose: In UPNP mode Feb 10 14:27:05 smsl-n100 volumio[805]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":12174,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"foobar2000 audio stream","artist":null,"album":null,"uri":"http://192.168.1.129:49885/39/stream.wav","trackType":"wav"} Feb 10 14:27:05 smsl-n100 volumio[805]: verbose: CURRENT POSITION 0 Feb 10 14:27:05 smsl-n100 volumio[805]: info: CoreStateMachine::syncState stateService pause Feb 10 14:27:05 smsl-n100 volumio[805]: info: CoreStateMachine::syncState currentStatus stop Feb 10 14:27:05 smsl-n100 volumio[805]: info: CoreStateMachine::pushState Feb 10 14:27:05 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushState Feb 10 14:27:05 smsl-n100 volumio[805]: info: ControllerMpd::pushState Feb 10 14:27:05 smsl-n100 volumio[805]: info: CoreCommandRouter::servicePushState Feb 10 14:27:05 smsl-n100 volumio[805]: verbose: In UPNP mode Feb 10 14:27:05 smsl-n100 volumio[805]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":12174,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"foobar2000 audio stream","artist":null,"album":null,"uri":"http://192.168.1.129:49885/39/stream.wav","trackType":"wav"} Feb 10 14:27:05 smsl-n100 volumio[805]: verbose: CURRENT POSITION 0 Feb 10 14:27:05 smsl-n100 volumio[805]: info: CoreStateMachine::syncState stateService pause Feb 10 14:27:05 smsl-n100 volumio[805]: info: CoreStateMachine::syncState currentStatus stop Feb 10 14:27:05 smsl-n100 volumio[805]: info: CoreStateMachine::pushState Feb 10 14:27:05 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushState Feb 10 14:27:05 smsl-n100 volumio[805]: info: ControllerMpd::pushState Feb 10 14:27:05 smsl-n100 volumio[805]: info: CoreCommandRouter::servicePushState Feb 10 14:27:05 smsl-n100 volumio[805]: verbose: In UPNP mode Feb 10 14:27:05 smsl-n100 volumio[805]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":12174,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"foobar2000 audio stream","artist":null,"album":null,"uri":"http://192.168.1.129:49885/39/stream.wav","trackType":"wav"} Feb 10 14:27:05 smsl-n100 volumio[805]: verbose: CURRENT POSITION 0 Feb 10 14:27:05 smsl-n100 volumio[805]: info: CoreStateMachine::syncState stateService pause Feb 10 14:27:05 smsl-n100 volumio[805]: info: CoreStateMachine::syncState currentStatus stop Feb 10 14:27:05 smsl-n100 volumio[805]: info: CoreStateMachine::pushState Feb 10 14:27:05 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushState Feb 10 14:27:05 smsl-n100 volumio[805]: info: ControllerMpd::pushState Feb 10 14:27:05 smsl-n100 volumio[805]: info: CoreCommandRouter::servicePushState Feb 10 14:27:05 smsl-n100 volumio[805]: verbose: In UPNP mode Feb 10 14:27:05 smsl-n100 volumio[805]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":12174,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"foobar2000 audio stream","artist":null,"album":null,"uri":"http://192.168.1.129:49885/39/stream.wav","trackType":"wav"} Feb 10 14:27:05 smsl-n100 volumio[805]: verbose: CURRENT POSITION 0 Feb 10 14:27:05 smsl-n100 volumio[805]: info: CoreStateMachine::syncState stateService pause Feb 10 14:27:05 smsl-n100 volumio[805]: info: CoreStateMachine::syncState currentStatus stop Feb 10 14:27:05 smsl-n100 volumio[805]: info: CoreStateMachine::pushState Feb 10 14:27:05 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushState Feb 10 14:27:05 smsl-n100 volumio[805]: info: ------------------------------ 161ms Feb 10 14:27:05 smsl-n100 volumio[805]: info: ------------------------------ 157ms Feb 10 14:27:05 smsl-n100 volumio[805]: info: ------------------------------ 133ms Feb 10 14:27:05 smsl-n100 volumio[805]: info: ------------------------------ 130ms Feb 10 14:27:05 smsl-n100 volumio[805]: info: CoreStateMachine::pushState Feb 10 14:27:05 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushState Feb 10 14:27:05 smsl-n100 volumio[805]: error: Serial API: Failed to decode command: 100, message: undefined Feb 10 14:27:05 smsl-n100 volumio[805]: error: Serial API: Failed to decode command: MAXVOL, message: Feb 10 14:27:05 smsl-n100 volumio[805]: error: Serial API: Failed to decode command: 100, message: undefined Feb 10 14:27:05 smsl-n100 volumio[805]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Feb 10 14:27:05 smsl-n100 volumio[805]: info: Feb 10 14:27:05 smsl-n100 volumio[805]: ---------------------------- MPD announces state update: player Feb 10 14:27:05 smsl-n100 volumio[805]: info: ControllerMpd::getState Feb 10 14:27:05 smsl-n100 volumio[805]: verbose: ControllerMpd::sendMpdCommand status Feb 10 14:27:05 smsl-n100 volumio[805]: info: Feb 10 14:27:05 smsl-n100 volumio[805]: ---------------------------- MPD announces state update: player Feb 10 14:27:05 smsl-n100 volumio[805]: info: ControllerMpd::getState Feb 10 14:27:05 smsl-n100 volumio[805]: verbose: ControllerMpd::sendMpdCommand status Feb 10 14:27:05 smsl-n100 volumio[805]: info: Feb 10 14:27:05 smsl-n100 volumio[805]: ---------------------------- MPD announces state update: player Feb 10 14:27:05 smsl-n100 volumio[805]: info: ControllerMpd::getState Feb 10 14:27:05 smsl-n100 volumio[805]: verbose: ControllerMpd::sendMpdCommand status Feb 10 14:27:05 smsl-n100 volumio[805]: verbose: ControllerMpd::sendMpdCommand clearerror Feb 10 14:27:05 smsl-n100 volumio[805]: info: sendMpdCommand status took 14 milliseconds Feb 10 14:27:05 smsl-n100 volumio[805]: verbose: ControllerMpd::sendMpdCommand clearerror Feb 10 14:27:05 smsl-n100 volumio[805]: info: sendMpdCommand status took 13 milliseconds Feb 10 14:27:05 smsl-n100 volumio[805]: verbose: ControllerMpd::sendMpdCommand clearerror Feb 10 14:27:05 smsl-n100 volumio[805]: info: sendMpdCommand status took 11 milliseconds Feb 10 14:27:05 smsl-n100 volumio[805]: verbose: ControllerMpd::parseState Feb 10 14:27:05 smsl-n100 volumio[805]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 14:27:05 smsl-n100 volumio[805]: verbose: ControllerMpd::parseState Feb 10 14:27:05 smsl-n100 volumio[805]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 14:27:05 smsl-n100 volumio[805]: verbose: ControllerMpd::parseState Feb 10 14:27:05 smsl-n100 volumio[805]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 14:27:05 smsl-n100 volumio[805]: info: sendMpdCommand clearerror took 17 milliseconds Feb 10 14:27:05 smsl-n100 volumio[805]: info: sendMpdCommand clearerror took 16 milliseconds Feb 10 14:27:05 smsl-n100 volumio[805]: info: sendMpdCommand clearerror took 14 milliseconds Feb 10 14:27:05 smsl-n100 volumio[805]: info: sendMpdCommand playlistinfo took 9 milliseconds Feb 10 14:27:05 smsl-n100 volumio[805]: info: sendMpdCommand playlistinfo took 8 milliseconds Feb 10 14:27:05 smsl-n100 volumio[805]: info: sendMpdCommand playlistinfo took 7 milliseconds Feb 10 14:27:05 smsl-n100 volumio[805]: verbose: ControllerMpd::parseTrackInfo Feb 10 14:27:05 smsl-n100 volumio[805]: verbose: ControllerMpd::parseTrackInfo Feb 10 14:27:05 smsl-n100 volumio[805]: verbose: ControllerMpd::parseTrackInfo Feb 10 14:27:05 smsl-n100 volumio[805]: info: ControllerMpd::pushState Feb 10 14:27:05 smsl-n100 volumio[805]: info: CoreCommandRouter::servicePushState Feb 10 14:27:05 smsl-n100 volumio[805]: verbose: In UPNP mode Feb 10 14:27:05 smsl-n100 volumio[805]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":12174,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"foobar2000 audio stream","artist":null,"album":null,"uri":"http://192.168.1.129:49885/39/stream.wav","trackType":"wav"} Feb 10 14:27:05 smsl-n100 volumio[805]: verbose: CURRENT POSITION 0 Feb 10 14:27:05 smsl-n100 volumio[805]: info: CoreStateMachine::syncState stateService pause Feb 10 14:27:05 smsl-n100 volumio[805]: info: CoreStateMachine::syncState currentStatus stop Feb 10 14:27:05 smsl-n100 volumio[805]: info: CoreStateMachine::pushState Feb 10 14:27:05 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushState Feb 10 14:27:05 smsl-n100 volumio[805]: info: ControllerMpd::pushState Feb 10 14:27:05 smsl-n100 volumio[805]: info: CoreCommandRouter::servicePushState Feb 10 14:27:05 smsl-n100 volumio[805]: verbose: In UPNP mode Feb 10 14:27:05 smsl-n100 volumio[805]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":12174,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"foobar2000 audio stream","artist":null,"album":null,"uri":"http://192.168.1.129:49885/39/stream.wav","trackType":"wav"} Feb 10 14:27:05 smsl-n100 volumio[805]: verbose: CURRENT POSITION 0 Feb 10 14:27:05 smsl-n100 volumio[805]: info: CoreStateMachine::syncState stateService pause Feb 10 14:27:05 smsl-n100 volumio[805]: info: CoreStateMachine::syncState currentStatus stop Feb 10 14:27:05 smsl-n100 volumio[805]: info: CoreStateMachine::pushState Feb 10 14:27:05 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushState Feb 10 14:27:05 smsl-n100 volumio[805]: info: ControllerMpd::pushState Feb 10 14:27:05 smsl-n100 volumio[805]: info: CoreCommandRouter::servicePushState Feb 10 14:27:05 smsl-n100 volumio[805]: verbose: In UPNP mode Feb 10 14:27:05 smsl-n100 volumio[805]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":12174,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"foobar2000 audio stream","artist":null,"album":null,"uri":"http://192.168.1.129:49885/39/stream.wav","trackType":"wav"} Feb 10 14:27:05 smsl-n100 volumio[805]: verbose: CURRENT POSITION 0 Feb 10 14:27:05 smsl-n100 volumio[805]: info: CoreStateMachine::syncState stateService pause Feb 10 14:27:05 smsl-n100 volumio[805]: info: CoreStateMachine::syncState currentStatus stop Feb 10 14:27:05 smsl-n100 volumio[805]: info: CoreStateMachine::pushState Feb 10 14:27:05 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushState Feb 10 14:27:05 smsl-n100 volumio[805]: info: ------------------------------ 69ms Feb 10 14:27:05 smsl-n100 volumio[805]: info: ------------------------------ 66ms Feb 10 14:27:05 smsl-n100 volumio[805]: info: ------------------------------ 64ms Feb 10 14:27:12 smsl-n100 kernel: usb 2-1.4: new high-speed USB device number 5 using ehci-platform Feb 10 14:27:12 smsl-n100 kernel: usb 2-1.4: New USB device found, idVendor=2a39, idProduct=3fd3, bcdDevice= 0.01 Feb 10 14:27:12 smsl-n100 kernel: usb 2-1.4: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Feb 10 14:27:12 smsl-n100 kernel: usb 2-1.4: Product: ADI-2 DAC (59930700) Feb 10 14:27:12 smsl-n100 kernel: usb 2-1.4: Manufacturer: RME Feb 10 14:27:12 smsl-n100 kernel: usb 2-1.4: SerialNumber: FF37BD9D1874DC8 Feb 10 14:27:12 smsl-n100 volumio[805]: info: No valid Plugin REST Endpoint: scanAudioInputs Feb 10 14:27:12 smsl-n100 volumio[805]: info: Feb 10 14:27:12 smsl-n100 volumio[805]: ---------------------------- USB Audio Device Attached Feb 10 14:27:12 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Feb 10 14:27:12 smsl-n100 volumio[805]: info: CoreCommandRouter::Close All Modals sent Feb 10 14:27:12 smsl-n100 volumio[805]: info: Preparing to save Alsa Options, stopping services first Feb 10 14:27:12 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioGetState Feb 10 14:27:12 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPause Feb 10 14:27:12 smsl-n100 volumio[805]: info: CoreStateMachine::pause Feb 10 14:27:12 smsl-n100 volumio[805]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"ADI-2 DAC (59930700)","alsacard":"DAC59930700"},"i2s":false} Feb 10 14:27:12 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 10 14:27:12 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 10 14:27:12 smsl-n100 volumio[805]: info: Setting mixer AES Rate for card ADI-2 DAC (59930700) Feb 10 14:27:12 smsl-n100 vtcs[1360]: [2026-02-10 14:27:12.857] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Feb 10 14:27:12 smsl-n100 vtcs[1360]: [2026-02-10 14:27:12.859] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Feb 10 14:27:12 smsl-n100 sudo[15774]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 10 14:27:12 smsl-n100 sudo[15774]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 14:27:12 smsl-n100 systemd[1]: Stopping Volumio Tidal Connect Service... Feb 10 14:27:12 smsl-n100 systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Feb 10 14:27:12 smsl-n100 systemd[1]: vtcs.service: Succeeded. Feb 10 14:27:12 smsl-n100 systemd[1]: Stopped Volumio Tidal Connect Service. Feb 10 14:27:13 smsl-n100 sudo[15774]: pam_unix(sudo:session): session closed for user root Feb 10 14:27:13 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioUpdateVolumeSettings Feb 10 14:27:13 smsl-n100 volumio[805]: info: Updating Volume Controller Parameters: Device: 5 Name: ADI-2 DAC (59930700) Mixer: AES Rate Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Feb 10 14:27:13 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Feb 10 14:27:13 smsl-n100 volumio[805]: info: Disabling external Volume Control Feb 10 14:27:13 smsl-n100 volumio[805]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 10 14:27:13 smsl-n100 volumio[805]: info: Preparing to generate the ALSA configuration file Feb 10 14:27:13 smsl-n100 volumio[805]: Unable to parse: Feb 10 14:27:13 smsl-n100 volumio[805]: Simple mixer control 'AES Rate',0 Feb 10 14:27:13 smsl-n100 volumio[805]: Capabilities: volume volume-joined Feb 10 14:27:13 smsl-n100 volumio[805]: Playback channels: Mono Feb 10 14:27:13 smsl-n100 volumio[805]: Capture channels: Mono Feb 10 14:27:13 smsl-n100 volumio[805]: Limits: 0 - 200000 Feb 10 14:27:13 smsl-n100 volumio[805]: Mono: 0 [0%] Feb 10 14:27:13 smsl-n100 volumio[805]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Feb 10 14:27:13 smsl-n100 sudo[15786]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 10 14:27:13 smsl-n100 sudo[15786]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 14:27:13 smsl-n100 sudo[15786]: pam_unix(sudo:session): session closed for user root Feb 10 14:27:13 smsl-n100 volumio[805]: info: Asound.conf file unchanged, so no further update is needed Feb 10 14:27:13 smsl-n100 volumio[805]: info: Output device has changed, restarting MPD Feb 10 14:27:13 smsl-n100 sudo[15791]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 10 14:27:13 smsl-n100 sudo[15791]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 14:27:13 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 14:27:13 smsl-n100 sudo[15791]: pam_unix(sudo:session): session closed for user root Feb 10 14:27:13 smsl-n100 sudo[15794]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 10 14:27:13 smsl-n100 sudo[15794]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 14:27:13 smsl-n100 systemd[1]: Stopping Music Player Daemon... Feb 10 14:27:13 smsl-n100 sudo[15797]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 10 14:27:13 smsl-n100 sudo[15797]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 14:27:13 smsl-n100 sudo[15800]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 10 14:27:13 smsl-n100 sudo[15800]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 14:27:13 smsl-n100 sudo[15797]: pam_unix(sudo:session): session closed for user root Feb 10 14:27:13 smsl-n100 systemd[1]: mpd.service: Succeeded. Feb 10 14:27:13 smsl-n100 systemd[1]: Stopped Music Player Daemon. Feb 10 14:27:13 smsl-n100 systemd[1]: Starting Music Player Daemon... Feb 10 14:27:13 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 10 14:27:13 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 14:27:13 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 10 14:27:13 smsl-n100 sudo[15800]: pam_unix(sudo:session): session closed for user root Feb 10 14:27:13 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 10 14:27:13 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 10 14:27:13 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 10 14:27:13 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 10 14:27:13 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 10 14:27:13 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 10 14:27:13 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 10 14:27:13 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 10 14:27:13 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 10 14:27:13 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Feb 10 14:27:13 smsl-n100 volumio[805]: info: MPD Permissions set Feb 10 14:27:13 smsl-n100 volumio[805]: Unable to parse: Feb 10 14:27:13 smsl-n100 volumio[805]: Simple mixer control 'AES Rate',0 Feb 10 14:27:13 smsl-n100 volumio[805]: Capabilities: volume volume-joined Feb 10 14:27:13 smsl-n100 volumio[805]: Playback channels: Mono Feb 10 14:27:13 smsl-n100 volumio[805]: Capture channels: Mono Feb 10 14:27:13 smsl-n100 volumio[805]: Limits: 0 - 200000 Feb 10 14:27:13 smsl-n100 volumio[805]: Mono: 0 [0%] Feb 10 14:27:13 smsl-n100 volumio[805]: info: VolumeController:: Volume=undefined Mute =false Feb 10 14:27:13 smsl-n100 volumio[805]: info: CoreStateMachine::pushState Feb 10 14:27:13 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 14:27:13 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushState Feb 10 14:27:13 smsl-n100 sudo[15807]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 10 14:27:13 smsl-n100 sudo[15807]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 14:27:13 smsl-n100 sudo[15807]: pam_unix(sudo:session): session closed for user root Feb 10 14:27:13 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 14:27:13 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 14:27:13 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 14:27:13 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 14:27:13 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 14:27:13 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 14:27:13 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 14:27:13 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 10 14:27:13 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 14:27:15 smsl-n100 volumio[805]: info: Clearing queue after UPNP request Feb 10 14:27:15 smsl-n100 volumio[805]: info: CoreStateMachine::ClearQueue Feb 10 14:27:15 smsl-n100 volumio[805]: info: CoreStateMachine::stop Feb 10 14:27:15 smsl-n100 volumio[805]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 10 14:27:15 smsl-n100 volumio[805]: info: CorePlayQueue::clearPlayQueue Feb 10 14:27:15 smsl-n100 volumio[805]: info: CorePlayQueue::saveQueue Feb 10 14:27:15 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushState Feb 10 14:27:15 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushQueue Feb 10 14:27:15 smsl-n100 volumio[805]: error: Upnp client error: Error: This socket has been ended by the other party Feb 10 14:27:15 smsl-n100 volumio[805]: info: CoreStateMachine::pushState Feb 10 14:27:15 smsl-n100 volumio[805]: info: CorePlayQueue::getTrack 0 Feb 10 14:27:15 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 14:27:15 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushState Feb 10 14:27:15 smsl-n100 volumio[805]: error: Serial API: Failed to decode command: 100, message: undefined Feb 10 14:27:15 smsl-n100 volumio[805]: error: Serial API: Failed to decode command: MAXVOL, message: Feb 10 14:27:15 smsl-n100 volumio[805]: error: Serial API: Failed to decode command: 100, message: undefined Feb 10 14:27:15 smsl-n100 volumio[805]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Feb 10 14:27:16 smsl-n100 volumio[805]: info: TidalConnect service stoped! Feb 10 14:27:16 smsl-n100 volumio[805]: info: TidalConnect service stoped! Feb 10 14:27:16 smsl-n100 volumio[805]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Feb 10 14:27:16 smsl-n100 volumio[805]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Feb 10 14:27:16 smsl-n100 sudo[15832]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Feb 10 14:27:16 smsl-n100 sudo[15832]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 14:27:16 smsl-n100 systemd[1]: Started Volumio Tidal Connect Service. Feb 10 14:27:16 smsl-n100 sudo[15832]: pam_unix(sudo:session): session closed for user root Feb 10 14:27:16 smsl-n100 volumio[805]: info: TidalConnect service stoped! Feb 10 14:27:16 smsl-n100 volumio[805]: info: TidalConnect service stoped! Feb 10 14:27:16 smsl-n100 mpd[15812]: Feb 10 14:27 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 10 14:27:16 smsl-n100 systemd[1]: Started Music Player Daemon. Feb 10 14:27:16 smsl-n100 sudo[15794]: pam_unix(sudo:session): session closed for user root Feb 10 14:27:16 smsl-n100 volumio[805]: error: updateQueue error: null Feb 10 14:27:16 smsl-n100 volumio[805]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Feb 10 14:27:16 smsl-n100 volumio[805]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Feb 10 14:27:16 smsl-n100 sudo[15848]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Feb 10 14:27:16 smsl-n100 sudo[15848]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 14:27:16 smsl-n100 sudo[15848]: pam_unix(sudo:session): session closed for user root Feb 10 14:27:17 smsl-n100 volumio[805]: info: Executing endpoint tc_getconfig Feb 10 14:27:17 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Feb 10 14:27:17 smsl-n100 vtcs[15834]: STARTING TidalConnect services, version: 1.5.1-beta1 Feb 10 14:27:17 smsl-n100 vtcs[15834]: STARTED TidalConnect services. Feb 10 14:27:17 smsl-n100 volumio[805]: info: Executing endpoint tc_connect Feb 10 14:27:17 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Feb 10 14:27:17 smsl-n100 volumio[805]: info: Connecting to TidalConnect Feb 10 14:27:17 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Feb 10 14:27:17 smsl-n100 volumio[805]: info: CoreStateMachine::getcurrentVolume Feb 10 14:27:17 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioRetrievevolume Feb 10 14:27:17 smsl-n100 volumio[805]: info: CoreCommandRouter::servicePushState Feb 10 14:27:17 smsl-n100 volumio[805]: info: CoreStateMachine::pushState Feb 10 14:27:17 smsl-n100 volumio[805]: info: CorePlayQueue::getTrack 0 Feb 10 14:27:17 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushState Feb 10 14:27:17 smsl-n100 volumio[805]: info: CorePlayQueue::getTrack 0 Feb 10 14:27:17 smsl-n100 volumio[805]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Feb 10 14:27:17 smsl-n100 volumio[805]: verbose: CURRENT POSITION 0 Feb 10 14:27:17 smsl-n100 volumio[805]: info: CoreStateMachine::syncState stateService stop Feb 10 14:27:17 smsl-n100 volumio[805]: info: CoreStateMachine::syncState currentStatus stop Feb 10 14:27:17 smsl-n100 volumio[805]: info: CoreStateMachine::pushState Feb 10 14:27:17 smsl-n100 volumio[805]: info: CorePlayQueue::getTrack 0 Feb 10 14:27:17 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 14:27:17 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushState Feb 10 14:27:17 smsl-n100 volumio[805]: info: No code Feb 10 14:27:17 smsl-n100 volumio[805]: info: CoreStateMachine::pushState Feb 10 14:27:17 smsl-n100 volumio[805]: info: CorePlayQueue::getTrack 0 Feb 10 14:27:17 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushState Feb 10 14:27:17 smsl-n100 volumio[805]: info: CoreCommandRouter::servicePushState Feb 10 14:27:17 smsl-n100 volumio[805]: info: CoreStateMachine::pushState Feb 10 14:27:17 smsl-n100 volumio[805]: info: CorePlayQueue::getTrack 0 Feb 10 14:27:17 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushState Feb 10 14:27:17 smsl-n100 volumio[805]: info: CorePlayQueue::getTrack 0 Feb 10 14:27:17 smsl-n100 volumio[805]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Feb 10 14:27:17 smsl-n100 volumio[805]: verbose: CURRENT POSITION 0 Feb 10 14:27:17 smsl-n100 volumio[805]: info: CoreStateMachine::syncState stateService stop Feb 10 14:27:17 smsl-n100 volumio[805]: info: CoreStateMachine::syncState currentStatus stop Feb 10 14:27:17 smsl-n100 volumio[805]: info: CoreStateMachine::pushState Feb 10 14:27:17 smsl-n100 volumio[805]: info: CorePlayQueue::getTrack 0 Feb 10 14:27:17 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushState Feb 10 14:27:17 smsl-n100 volumio[805]: info: No code Feb 10 14:27:17 smsl-n100 volumio[805]: info: CoreStateMachine::pushState Feb 10 14:27:17 smsl-n100 volumio[805]: info: CorePlayQueue::getTrack 0 Feb 10 14:27:17 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushState Feb 10 14:27:17 smsl-n100 volumio[805]: Unable to parse: Feb 10 14:27:17 smsl-n100 volumio[805]: Simple mixer control 'AES Rate',0 Feb 10 14:27:17 smsl-n100 volumio[805]: Capabilities: volume volume-joined Feb 10 14:27:17 smsl-n100 volumio[805]: Playback channels: Mono Feb 10 14:27:17 smsl-n100 volumio[805]: Capture channels: Mono Feb 10 14:27:17 smsl-n100 volumio[805]: Limits: 0 - 200000 Feb 10 14:27:17 smsl-n100 volumio[805]: Mono: 0 [0%] Feb 10 14:27:17 smsl-n100 volumio[805]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Feb 10 14:27:17 smsl-n100 volumio[805]: Unable to parse: Feb 10 14:27:17 smsl-n100 volumio[805]: Simple mixer control 'AES Rate',0 Feb 10 14:27:17 smsl-n100 volumio[805]: Capabilities: volume volume-joined Feb 10 14:27:17 smsl-n100 volumio[805]: Playback channels: Mono Feb 10 14:27:17 smsl-n100 volumio[805]: Capture channels: Mono Feb 10 14:27:17 smsl-n100 volumio[805]: Limits: 0 - 200000 Feb 10 14:27:17 smsl-n100 volumio[805]: Mono: 0 [0%] Feb 10 14:27:17 smsl-n100 volumio[805]: info: VolumeController:: Volume=undefined Mute =false Feb 10 14:27:17 smsl-n100 volumio[805]: info: CoreStateMachine::pushState Feb 10 14:27:17 smsl-n100 volumio[805]: info: CorePlayQueue::getTrack 0 Feb 10 14:27:17 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 14:27:17 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushState Feb 10 14:27:17 smsl-n100 volumio[805]: info: CoreStateMachine::updateTrackBlock Feb 10 14:27:17 smsl-n100 volumio[805]: info: CorePlayQueue::getTrackBlock Feb 10 14:27:17 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioRetrievevolume Feb 10 14:27:17 smsl-n100 volumio[805]: Unable to parse: Feb 10 14:27:17 smsl-n100 volumio[805]: Simple mixer control 'AES Rate',0 Feb 10 14:27:17 smsl-n100 volumio[805]: Capabilities: volume volume-joined Feb 10 14:27:17 smsl-n100 volumio[805]: Playback channels: Mono Feb 10 14:27:17 smsl-n100 volumio[805]: Capture channels: Mono Feb 10 14:27:17 smsl-n100 volumio[805]: Limits: 0 - 200000 Feb 10 14:27:17 smsl-n100 volumio[805]: Mono: 0 [0%] Feb 10 14:27:17 smsl-n100 volumio[805]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Feb 10 14:27:17 smsl-n100 volumio[805]: Unable to parse: Feb 10 14:27:17 smsl-n100 volumio[805]: Simple mixer control 'AES Rate',0 Feb 10 14:27:17 smsl-n100 volumio[805]: Capabilities: volume volume-joined Feb 10 14:27:17 smsl-n100 volumio[805]: Playback channels: Mono Feb 10 14:27:17 smsl-n100 volumio[805]: Capture channels: Mono Feb 10 14:27:17 smsl-n100 volumio[805]: Limits: 0 - 200000 Feb 10 14:27:17 smsl-n100 volumio[805]: Mono: 0 [0%] Feb 10 14:27:17 smsl-n100 volumio[805]: info: VolumeController:: Volume=undefined Mute =false Feb 10 14:27:17 smsl-n100 volumio[805]: info: CoreStateMachine::pushState Feb 10 14:27:17 smsl-n100 volumio[805]: info: CorePlayQueue::getTrack 0 Feb 10 14:27:17 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushState Feb 10 14:27:17 smsl-n100 volumio[805]: info: Clearing queue after UPNP request Feb 10 14:27:17 smsl-n100 volumio[805]: info: CoreStateMachine::ClearQueue Feb 10 14:27:17 smsl-n100 volumio[805]: info: CoreStateMachine::stop Feb 10 14:27:17 smsl-n100 volumio[805]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 10 14:27:17 smsl-n100 volumio[805]: info: CorePlayQueue::clearPlayQueue Feb 10 14:27:17 smsl-n100 volumio[805]: info: CorePlayQueue::saveQueue Feb 10 14:27:17 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushState Feb 10 14:27:17 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushQueue Feb 10 14:27:17 smsl-n100 volumio[805]: info: Feb 10 14:27:17 smsl-n100 volumio[805]: ---------------------------- MPD announces system playlist update Feb 10 14:27:17 smsl-n100 volumio[805]: info: Ignoring MPD Status Update Feb 10 14:27:17 smsl-n100 volumio[805]: error: updateQueue error: null Feb 10 14:27:17 smsl-n100 volumio[805]: info: ------------------------------ 4ms Feb 10 14:27:17 smsl-n100 volumio[805]: info: Starting UPNP Playback Feb 10 14:27:17 smsl-n100 volumio[805]: info: Preparing playback through UPNP Feb 10 14:27:17 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioGetState Feb 10 14:27:17 smsl-n100 volumio[805]: info: CorePlayQueue::getTrack 0 Feb 10 14:27:17 smsl-n100 volumio[805]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 10 14:27:18 smsl-n100 volumio[805]: info: Feb 10 14:27:18 smsl-n100 volumio[805]: ---------------------------- MPD announces system playlist update Feb 10 14:27:18 smsl-n100 volumio[805]: info: Ignoring MPD Status Update Feb 10 14:27:18 smsl-n100 volumio[805]: info: Feb 10 14:27:18 smsl-n100 volumio[805]: ---------------------------- MPD announces system playlist update Feb 10 14:27:18 smsl-n100 volumio[805]: info: Ignoring MPD Status Update Feb 10 14:27:18 smsl-n100 volumio[805]: info: ------------------------------ 5ms Feb 10 14:27:18 smsl-n100 volumio[805]: info: ------------------------------ 4ms Feb 10 14:27:18 smsl-n100 volumio[805]: info: Feb 10 14:27:18 smsl-n100 volumio[805]: ---------------------------- MPD announces system playlist update Feb 10 14:27:18 smsl-n100 volumio[805]: info: Ignoring MPD Status Update Feb 10 14:27:18 smsl-n100 volumio[805]: info: Feb 10 14:27:18 smsl-n100 volumio[805]: ---------------------------- MPD announces system playlist update Feb 10 14:27:18 smsl-n100 volumio[805]: info: Ignoring MPD Status Update Feb 10 14:27:18 smsl-n100 volumio[805]: info: ------------------------------ 5ms Feb 10 14:27:18 smsl-n100 volumio[805]: info: ------------------------------ 4ms Feb 10 14:27:18 smsl-n100 volumio[805]: info: Feb 10 14:27:18 smsl-n100 volumio[805]: ---------------------------- MPD announces system playlist update Feb 10 14:27:18 smsl-n100 volumio[805]: info: Ignoring MPD Status Update Feb 10 14:27:18 smsl-n100 volumio[805]: info: Feb 10 14:27:18 smsl-n100 volumio[805]: ---------------------------- MPD announces system playlist update Feb 10 14:27:18 smsl-n100 volumio[805]: info: Ignoring MPD Status Update Feb 10 14:27:18 smsl-n100 volumio[805]: info: ------------------------------ 5ms Feb 10 14:27:18 smsl-n100 volumio[805]: info: ------------------------------ 5ms Feb 10 14:27:18 smsl-n100 volumio[805]: info: Feb 10 14:27:18 smsl-n100 volumio[805]: ---------------------------- MPD announces state update: player Feb 10 14:27:18 smsl-n100 volumio[805]: info: ControllerMpd::getState Feb 10 14:27:18 smsl-n100 volumio[805]: verbose: ControllerMpd::sendMpdCommand status Feb 10 14:27:18 smsl-n100 volumio[805]: info: Feb 10 14:27:18 smsl-n100 volumio[805]: ---------------------------- MPD announces state update: player Feb 10 14:27:18 smsl-n100 volumio[805]: info: sendMpdCommand status took 13 milliseconds Feb 10 14:27:18 smsl-n100 volumio[805]: info: ControllerMpd::getState Feb 10 14:27:18 smsl-n100 volumio[805]: verbose: ControllerMpd::sendMpdCommand status Feb 10 14:27:18 smsl-n100 volumio[805]: verbose: ControllerMpd::parseState Feb 10 14:27:18 smsl-n100 volumio[805]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 14:27:18 smsl-n100 volumio[805]: info: sendMpdCommand status took 9 milliseconds Feb 10 14:27:18 smsl-n100 volumio[805]: info: sendMpdCommand playlistinfo took 7 milliseconds Feb 10 14:27:18 smsl-n100 volumio[805]: verbose: ControllerMpd::parseState Feb 10 14:27:18 smsl-n100 volumio[805]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 14:27:18 smsl-n100 volumio[805]: verbose: ControllerMpd::parseTrackInfo Feb 10 14:27:18 smsl-n100 volumio[805]: info: ControllerMpd::pushState Feb 10 14:27:18 smsl-n100 volumio[805]: info: CoreCommandRouter::servicePushState Feb 10 14:27:18 smsl-n100 volumio[805]: verbose: In UPNP mode Feb 10 14:27:18 smsl-n100 volumio[805]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":12174,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"foobar2000 audio stream","artist":null,"album":null,"uri":"http://192.168.1.129:49885/40/stream.wav","trackType":"wav"} Feb 10 14:27:18 smsl-n100 volumio[805]: verbose: CURRENT POSITION 0 Feb 10 14:27:18 smsl-n100 volumio[805]: info: CoreStateMachine::syncState stateService play Feb 10 14:27:18 smsl-n100 volumio[805]: info: CoreStateMachine::syncState currentStatus stop Feb 10 14:27:18 smsl-n100 volumio[805]: info: ------------------------------ 46ms Feb 10 14:27:18 smsl-n100 volumio[805]: info: sendMpdCommand playlistinfo took 14 milliseconds Feb 10 14:27:18 smsl-n100 volumio[805]: verbose: ControllerMpd::parseTrackInfo Feb 10 14:27:18 smsl-n100 volumio[805]: info: ControllerMpd::pushState Feb 10 14:27:18 smsl-n100 volumio[805]: info: CoreCommandRouter::servicePushState Feb 10 14:27:18 smsl-n100 volumio[805]: verbose: In UPNP mode Feb 10 14:27:18 smsl-n100 volumio[805]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":12174,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"foobar2000 audio stream","artist":null,"album":null,"uri":"http://192.168.1.129:49885/40/stream.wav","trackType":"wav"} Feb 10 14:27:18 smsl-n100 volumio[805]: verbose: CURRENT POSITION 0 Feb 10 14:27:18 smsl-n100 volumio[805]: info: CoreStateMachine::syncState stateService play Feb 10 14:27:18 smsl-n100 volumio[805]: info: CoreStateMachine::syncState currentStatus play Feb 10 14:27:18 smsl-n100 volumio[805]: info: Received an update from plugin. extracting info from payload Feb 10 14:27:18 smsl-n100 volumio[805]: info: CoreStateMachine::pushState Feb 10 14:27:18 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 14:27:18 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushState Feb 10 14:27:18 smsl-n100 volumio[805]: info: CoreStateMachine::pushState Feb 10 14:27:18 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushState Feb 10 14:27:18 smsl-n100 volumio[805]: info: ------------------------------ 91ms Feb 10 14:27:18 smsl-n100 volumio[805]: info: Clearing queue after UPNP request Feb 10 14:27:18 smsl-n100 volumio[805]: info: CoreStateMachine::pushState Feb 10 14:27:18 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 14:27:18 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushState Feb 10 14:27:18 smsl-n100 volumio[805]: error: Serial API: Failed to decode command: 100, message: undefined Feb 10 14:27:18 smsl-n100 volumio[805]: error: Serial API: Failed to decode command: MAXVOL, message: Feb 10 14:27:18 smsl-n100 volumio[805]: error: Serial API: Failed to decode command: 100, message: undefined Feb 10 14:27:18 smsl-n100 volumio[805]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Feb 10 14:27:18 smsl-n100 volumio[805]: info: CoreStateMachine::ClearQueue Feb 10 14:27:18 smsl-n100 volumio[805]: info: CoreStateMachine::stop Feb 10 14:27:18 smsl-n100 volumio[805]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 10 14:27:18 smsl-n100 volumio[805]: info: CoreStateMachine::stPlaybackTimer Feb 10 14:27:18 smsl-n100 volumio[805]: info: CoreStateMachine::updateTrackBlock Feb 10 14:27:18 smsl-n100 volumio[805]: info: CorePlayQueue::getTrackBlock Feb 10 14:27:18 smsl-n100 volumio[805]: info: CoreStateMachine::pushState Feb 10 14:27:18 smsl-n100 volumio[805]: info: CorePlayQueue::getTrack 0 Feb 10 14:27:18 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 14:27:18 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushState Feb 10 14:27:18 smsl-n100 volumio[805]: info: CoreStateMachine::serviceStop Feb 10 14:27:18 smsl-n100 volumio[805]: info: CorePlayQueue::getTrack 0 Feb 10 14:27:18 smsl-n100 volumio[805]: info: ControllerMpd::stop Feb 10 14:27:18 smsl-n100 volumio[805]: verbose: ControllerMpd::sendMpdCommand stop Feb 10 14:27:18 smsl-n100 volumio[805]: info: CorePlayQueue::clearPlayQueue Feb 10 14:27:18 smsl-n100 volumio[805]: info: CorePlayQueue::saveQueue Feb 10 14:27:18 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushState Feb 10 14:27:18 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushQueue Feb 10 14:27:18 smsl-n100 volumio[805]: info: Feb 10 14:27:18 smsl-n100 volumio[805]: ---------------------------- MPD announces state update: player Feb 10 14:27:18 smsl-n100 volumio[805]: info: sendMpdCommand stop took 28 milliseconds Feb 10 14:27:18 smsl-n100 volumio[805]: info: ControllerMpd::getState Feb 10 14:27:18 smsl-n100 volumio[805]: verbose: ControllerMpd::sendMpdCommand status Feb 10 14:27:18 smsl-n100 volumio[805]: info: Feb 10 14:27:18 smsl-n100 volumio[805]: ---------------------------- MPD announces system playlist update Feb 10 14:27:18 smsl-n100 volumio[805]: info: Ignoring MPD Status Update Feb 10 14:27:18 smsl-n100 volumio[805]: info: sendMpdCommand status took 3 milliseconds Feb 10 14:27:18 smsl-n100 volumio[805]: verbose: ControllerMpd::parseState Feb 10 14:27:18 smsl-n100 volumio[805]: info: ControllerMpd::pushState Feb 10 14:27:18 smsl-n100 volumio[805]: info: CoreCommandRouter::servicePushState Feb 10 14:27:18 smsl-n100 volumio[805]: info: CoreStateMachine::pushState Feb 10 14:27:18 smsl-n100 volumio[805]: info: CorePlayQueue::getTrack 0 Feb 10 14:27:18 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushState Feb 10 14:27:18 smsl-n100 volumio[805]: info: CorePlayQueue::getTrack 0 Feb 10 14:27:18 smsl-n100 volumio[805]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 10 14:27:18 smsl-n100 volumio[805]: verbose: CURRENT POSITION 0 Feb 10 14:27:18 smsl-n100 volumio[805]: info: CoreStateMachine::syncState stateService stop Feb 10 14:27:18 smsl-n100 volumio[805]: info: CoreStateMachine::syncState currentStatus stop Feb 10 14:27:18 smsl-n100 volumio[805]: info: CoreStateMachine::pushState Feb 10 14:27:18 smsl-n100 volumio[805]: info: CorePlayQueue::getTrack 0 Feb 10 14:27:18 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushState Feb 10 14:27:18 smsl-n100 volumio[805]: info: No code Feb 10 14:27:18 smsl-n100 volumio[805]: info: CoreStateMachine::pushState Feb 10 14:27:18 smsl-n100 volumio[805]: info: CorePlayQueue::getTrack 0 Feb 10 14:27:18 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushState Feb 10 14:27:18 smsl-n100 volumio[805]: info: ------------------------------ 32ms Feb 10 14:27:18 smsl-n100 volumio[805]: error: updateQueue error: null Feb 10 14:27:18 smsl-n100 volumio[805]: info: ------------------------------ 33ms Feb 10 14:27:19 smsl-n100 volumio[805]: info: TidalConnect service started! Feb 10 14:27:19 smsl-n100 sudo[15902]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Feb 10 14:27:19 smsl-n100 sudo[15902]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 14:27:19 smsl-n100 systemd[1]: Stopping MPD Monitor Service... Feb 10 14:27:19 smsl-n100 systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Feb 10 14:27:19 smsl-n100 systemd[1]: mpd_monitor.service: Succeeded. Feb 10 14:27:19 smsl-n100 systemd[1]: Stopped MPD Monitor Service. Feb 10 14:27:19 smsl-n100 systemd[1]: Started MPD Monitor Service. Feb 10 14:27:19 smsl-n100 sudo[15902]: pam_unix(sudo:session): session closed for user root Feb 10 14:27:19 smsl-n100 volumio[805]: info: Successfully started MPD Monitor Feb 10 14:27:19 smsl-n100 mpd_monitor.sh[15904]: MPD Monitor Service: Starting MPD Monitor Service Feb 10 14:27:19 smsl-n100 volumio[805]: info: TidalConnect service started! Feb 10 14:27:22 smsl-n100 volumio[805]: info: Clearing queue after UPNP request Feb 10 14:27:22 smsl-n100 volumio[805]: info: CoreStateMachine::ClearQueue Feb 10 14:27:22 smsl-n100 volumio[805]: info: CoreStateMachine::stop Feb 10 14:27:22 smsl-n100 volumio[805]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 10 14:27:22 smsl-n100 volumio[805]: info: CorePlayQueue::clearPlayQueue Feb 10 14:27:22 smsl-n100 volumio[805]: info: CorePlayQueue::saveQueue Feb 10 14:27:22 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushState Feb 10 14:27:22 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushQueue Feb 10 14:27:22 smsl-n100 volumio[805]: info: Feb 10 14:27:22 smsl-n100 volumio[805]: ---------------------------- MPD announces system playlist update Feb 10 14:27:22 smsl-n100 volumio[805]: info: Ignoring MPD Status Update Feb 10 14:27:22 smsl-n100 volumio[805]: error: updateQueue error: null Feb 10 14:27:22 smsl-n100 volumio[805]: info: ------------------------------ 4ms Feb 10 14:27:22 smsl-n100 volumio[805]: info: Clearing queue after UPNP request Feb 10 14:27:22 smsl-n100 volumio[805]: info: CoreStateMachine::ClearQueue Feb 10 14:27:22 smsl-n100 volumio[805]: info: CoreStateMachine::stop Feb 10 14:27:22 smsl-n100 volumio[805]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 10 14:27:22 smsl-n100 volumio[805]: info: CorePlayQueue::clearPlayQueue Feb 10 14:27:22 smsl-n100 volumio[805]: info: CorePlayQueue::saveQueue Feb 10 14:27:22 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushState Feb 10 14:27:22 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushQueue Feb 10 14:27:22 smsl-n100 volumio[805]: info: Feb 10 14:27:22 smsl-n100 volumio[805]: ---------------------------- MPD announces system playlist update Feb 10 14:27:22 smsl-n100 volumio[805]: info: Ignoring MPD Status Update Feb 10 14:27:22 smsl-n100 volumio[805]: error: updateQueue error: null Feb 10 14:27:22 smsl-n100 volumio[805]: info: ------------------------------ 4ms Feb 10 14:27:22 smsl-n100 volumio[805]: info: Clearing queue after UPNP request Feb 10 14:27:23 smsl-n100 volumio[805]: info: CoreStateMachine::ClearQueue Feb 10 14:27:23 smsl-n100 volumio[805]: info: CoreStateMachine::stop Feb 10 14:27:23 smsl-n100 volumio[805]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 10 14:27:23 smsl-n100 volumio[805]: info: CorePlayQueue::clearPlayQueue Feb 10 14:27:23 smsl-n100 volumio[805]: info: CorePlayQueue::saveQueue Feb 10 14:27:23 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushState Feb 10 14:27:23 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushQueue Feb 10 14:27:23 smsl-n100 volumio[805]: info: Feb 10 14:27:23 smsl-n100 volumio[805]: ---------------------------- MPD announces system playlist update Feb 10 14:27:23 smsl-n100 volumio[805]: info: Ignoring MPD Status Update Feb 10 14:27:23 smsl-n100 volumio[805]: error: updateQueue error: null Feb 10 14:27:23 smsl-n100 volumio[805]: info: ------------------------------ 3ms Feb 10 14:27:23 smsl-n100 volumio[805]: info: Clearing queue after UPNP request Feb 10 14:27:23 smsl-n100 volumio[805]: info: CoreStateMachine::ClearQueue Feb 10 14:27:23 smsl-n100 volumio[805]: info: CoreStateMachine::stop Feb 10 14:27:23 smsl-n100 volumio[805]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 10 14:27:23 smsl-n100 volumio[805]: info: CorePlayQueue::clearPlayQueue Feb 10 14:27:23 smsl-n100 volumio[805]: info: CorePlayQueue::saveQueue Feb 10 14:27:23 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushState Feb 10 14:27:23 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushQueue Feb 10 14:27:23 smsl-n100 volumio[805]: info: Feb 10 14:27:23 smsl-n100 volumio[805]: ---------------------------- MPD announces system playlist update Feb 10 14:27:23 smsl-n100 volumio[805]: info: Ignoring MPD Status Update Feb 10 14:27:23 smsl-n100 volumio[805]: error: updateQueue error: null Feb 10 14:27:23 smsl-n100 volumio[805]: info: ------------------------------ 9ms Feb 10 14:27:23 smsl-n100 volumio[805]: info: Starting UPNP Playback Feb 10 14:27:23 smsl-n100 volumio[805]: info: Preparing playback through UPNP Feb 10 14:27:23 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioGetState Feb 10 14:27:23 smsl-n100 volumio[805]: info: CorePlayQueue::getTrack 0 Feb 10 14:27:23 smsl-n100 volumio[805]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 10 14:27:23 smsl-n100 volumio[805]: info: Feb 10 14:27:23 smsl-n100 volumio[805]: ---------------------------- MPD announces system playlist update Feb 10 14:27:23 smsl-n100 volumio[805]: info: Ignoring MPD Status Update Feb 10 14:27:23 smsl-n100 volumio[805]: info: Feb 10 14:27:23 smsl-n100 volumio[805]: ---------------------------- MPD announces system playlist update Feb 10 14:27:23 smsl-n100 volumio[805]: info: Ignoring MPD Status Update Feb 10 14:27:23 smsl-n100 volumio[805]: info: ------------------------------ 5ms Feb 10 14:27:23 smsl-n100 volumio[805]: info: ------------------------------ 4ms Feb 10 14:27:23 smsl-n100 volumio[805]: info: Feb 10 14:27:23 smsl-n100 volumio[805]: ---------------------------- MPD announces system playlist update Feb 10 14:27:23 smsl-n100 volumio[805]: info: Ignoring MPD Status Update Feb 10 14:27:23 smsl-n100 volumio[805]: info: Feb 10 14:27:23 smsl-n100 volumio[805]: ---------------------------- MPD announces system playlist update Feb 10 14:27:23 smsl-n100 volumio[805]: info: Ignoring MPD Status Update Feb 10 14:27:23 smsl-n100 volumio[805]: info: ------------------------------ 5ms Feb 10 14:27:23 smsl-n100 volumio[805]: info: ------------------------------ 4ms Feb 10 14:27:23 smsl-n100 volumio[805]: info: Feb 10 14:27:23 smsl-n100 volumio[805]: ---------------------------- MPD announces system playlist update Feb 10 14:27:23 smsl-n100 volumio[805]: info: Ignoring MPD Status Update Feb 10 14:27:23 smsl-n100 volumio[805]: info: Feb 10 14:27:23 smsl-n100 volumio[805]: ---------------------------- MPD announces system playlist update Feb 10 14:27:23 smsl-n100 volumio[805]: info: Ignoring MPD Status Update Feb 10 14:27:23 smsl-n100 volumio[805]: info: ------------------------------ 5ms Feb 10 14:27:23 smsl-n100 volumio[805]: info: ------------------------------ 4ms Feb 10 14:27:23 smsl-n100 volumio[805]: info: Feb 10 14:27:23 smsl-n100 volumio[805]: ---------------------------- MPD announces state update: player Feb 10 14:27:23 smsl-n100 volumio[805]: info: ControllerMpd::getState Feb 10 14:27:23 smsl-n100 volumio[805]: verbose: ControllerMpd::sendMpdCommand status Feb 10 14:27:23 smsl-n100 volumio[805]: info: sendMpdCommand status took 6 milliseconds Feb 10 14:27:23 smsl-n100 volumio[805]: verbose: ControllerMpd::parseState Feb 10 14:27:23 smsl-n100 volumio[805]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 14:27:23 smsl-n100 volumio[805]: info: Feb 10 14:27:23 smsl-n100 volumio[805]: ---------------------------- MPD announces state update: player Feb 10 14:27:23 smsl-n100 volumio[805]: info: ControllerMpd::getState Feb 10 14:27:23 smsl-n100 volumio[805]: verbose: ControllerMpd::sendMpdCommand status Feb 10 14:27:23 smsl-n100 volumio[805]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 10 14:27:23 smsl-n100 volumio[805]: verbose: ControllerMpd::parseTrackInfo Feb 10 14:27:23 smsl-n100 volumio[805]: info: ControllerMpd::pushState Feb 10 14:27:23 smsl-n100 volumio[805]: info: CoreCommandRouter::servicePushState Feb 10 14:27:23 smsl-n100 volumio[805]: verbose: In UPNP mode Feb 10 14:27:23 smsl-n100 volumio[805]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":12174,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"foobar2000 audio stream","artist":null,"album":null,"uri":"http://192.168.1.129:49885/41/stream.wav","trackType":"wav"} Feb 10 14:27:23 smsl-n100 volumio[805]: verbose: CURRENT POSITION 0 Feb 10 14:27:23 smsl-n100 volumio[805]: info: CoreStateMachine::syncState stateService play Feb 10 14:27:23 smsl-n100 volumio[805]: info: CoreStateMachine::syncState currentStatus stop Feb 10 14:27:23 smsl-n100 volumio[805]: info: ------------------------------ 28ms Feb 10 14:27:23 smsl-n100 volumio[805]: info: sendMpdCommand status took 14 milliseconds Feb 10 14:27:24 smsl-n100 volumio[805]: verbose: ControllerMpd::parseState Feb 10 14:27:24 smsl-n100 volumio[805]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 14:27:24 smsl-n100 volumio[805]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 10 14:27:24 smsl-n100 volumio[805]: verbose: ControllerMpd::parseTrackInfo Feb 10 14:27:24 smsl-n100 volumio[805]: info: ControllerMpd::pushState Feb 10 14:27:24 smsl-n100 volumio[805]: info: CoreCommandRouter::servicePushState Feb 10 14:27:24 smsl-n100 volumio[805]: verbose: In UPNP mode Feb 10 14:27:24 smsl-n100 volumio[805]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":12174,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"foobar2000 audio stream","artist":null,"album":null,"uri":"http://192.168.1.129:49885/41/stream.wav","trackType":"wav"} Feb 10 14:27:24 smsl-n100 volumio[805]: verbose: CURRENT POSITION 0 Feb 10 14:27:24 smsl-n100 volumio[805]: info: CoreStateMachine::syncState stateService play Feb 10 14:27:24 smsl-n100 volumio[805]: info: CoreStateMachine::syncState currentStatus play Feb 10 14:27:24 smsl-n100 volumio[805]: info: Received an update from plugin. extracting info from payload Feb 10 14:27:24 smsl-n100 volumio[805]: info: CoreStateMachine::pushState Feb 10 14:27:24 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 14:27:24 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushState Feb 10 14:27:24 smsl-n100 volumio[805]: info: CoreStateMachine::pushState Feb 10 14:27:24 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushState Feb 10 14:27:24 smsl-n100 volumio[805]: info: ------------------------------ 49ms Feb 10 14:27:24 smsl-n100 volumio[805]: info: CoreStateMachine::pushState Feb 10 14:27:24 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushState Feb 10 14:27:24 smsl-n100 volumio[805]: error: Serial API: Failed to decode command: 100, message: undefined Feb 10 14:27:24 smsl-n100 volumio[805]: error: Serial API: Failed to decode command: MAXVOL, message: Feb 10 14:27:24 smsl-n100 volumio[805]: error: Serial API: Failed to decode command: 100, message: undefined Feb 10 14:27:24 smsl-n100 volumio[805]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Feb 10 14:27:26 smsl-n100 volumio[805]: info: Clearing queue after UPNP request Feb 10 14:27:26 smsl-n100 volumio[805]: info: CoreStateMachine::ClearQueue Feb 10 14:27:26 smsl-n100 volumio[805]: info: CoreStateMachine::stop Feb 10 14:27:26 smsl-n100 volumio[805]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 10 14:27:26 smsl-n100 volumio[805]: info: CoreStateMachine::stPlaybackTimer Feb 10 14:27:26 smsl-n100 volumio[805]: info: CoreStateMachine::updateTrackBlock Feb 10 14:27:26 smsl-n100 volumio[805]: info: CorePlayQueue::getTrackBlock Feb 10 14:27:26 smsl-n100 volumio[805]: info: CoreStateMachine::pushState Feb 10 14:27:26 smsl-n100 volumio[805]: info: CorePlayQueue::getTrack 0 Feb 10 14:27:26 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 14:27:26 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushState Feb 10 14:27:26 smsl-n100 volumio[805]: info: CoreStateMachine::serviceStop Feb 10 14:27:26 smsl-n100 volumio[805]: info: CorePlayQueue::getTrack 0 Feb 10 14:27:26 smsl-n100 volumio[805]: info: ControllerMpd::stop Feb 10 14:27:26 smsl-n100 volumio[805]: verbose: ControllerMpd::sendMpdCommand stop Feb 10 14:27:26 smsl-n100 volumio[805]: info: CorePlayQueue::clearPlayQueue Feb 10 14:27:26 smsl-n100 volumio[805]: info: CorePlayQueue::saveQueue Feb 10 14:27:26 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushState Feb 10 14:27:26 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushQueue Feb 10 14:27:26 smsl-n100 volumio[805]: info: sendMpdCommand stop took 29 milliseconds Feb 10 14:27:26 smsl-n100 volumio[805]: info: Feb 10 14:27:26 smsl-n100 volumio[805]: ---------------------------- MPD announces state update: player Feb 10 14:27:26 smsl-n100 volumio[805]: info: ControllerMpd::getState Feb 10 14:27:26 smsl-n100 volumio[805]: verbose: ControllerMpd::sendMpdCommand status Feb 10 14:27:26 smsl-n100 volumio[805]: info: Feb 10 14:27:26 smsl-n100 volumio[805]: ---------------------------- MPD announces system playlist update Feb 10 14:27:26 smsl-n100 volumio[805]: info: Ignoring MPD Status Update Feb 10 14:27:26 smsl-n100 volumio[805]: info: sendMpdCommand status took 4 milliseconds Feb 10 14:27:26 smsl-n100 volumio[805]: verbose: ControllerMpd::parseState Feb 10 14:27:26 smsl-n100 volumio[805]: info: ControllerMpd::pushState Feb 10 14:27:26 smsl-n100 volumio[805]: info: CoreCommandRouter::servicePushState Feb 10 14:27:26 smsl-n100 volumio[805]: info: CoreStateMachine::pushState Feb 10 14:27:26 smsl-n100 volumio[805]: info: CorePlayQueue::getTrack 0 Feb 10 14:27:26 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushState Feb 10 14:27:26 smsl-n100 volumio[805]: info: CorePlayQueue::getTrack 0 Feb 10 14:27:26 smsl-n100 volumio[805]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 10 14:27:26 smsl-n100 volumio[805]: verbose: CURRENT POSITION 0 Feb 10 14:27:26 smsl-n100 volumio[805]: info: CoreStateMachine::syncState stateService stop Feb 10 14:27:26 smsl-n100 volumio[805]: info: CoreStateMachine::syncState currentStatus stop Feb 10 14:27:26 smsl-n100 volumio[805]: info: CoreStateMachine::pushState Feb 10 14:27:26 smsl-n100 volumio[805]: info: CorePlayQueue::getTrack 0 Feb 10 14:27:26 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushState Feb 10 14:27:26 smsl-n100 volumio[805]: info: No code Feb 10 14:27:26 smsl-n100 volumio[805]: info: CoreStateMachine::pushState Feb 10 14:27:26 smsl-n100 volumio[805]: info: CorePlayQueue::getTrack 0 Feb 10 14:27:26 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushState Feb 10 14:27:26 smsl-n100 volumio[805]: info: ------------------------------ 32ms Feb 10 14:27:26 smsl-n100 volumio[805]: error: updateQueue error: null Feb 10 14:27:26 smsl-n100 volumio[805]: info: ------------------------------ 31ms Feb 10 14:27:26 smsl-n100 volumio[805]: info: Clearing queue after UPNP request Feb 10 14:27:26 smsl-n100 volumio[805]: info: CoreStateMachine::pushState Feb 10 14:27:26 smsl-n100 volumio[805]: info: CorePlayQueue::getTrack 0 Feb 10 14:27:26 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushState Feb 10 14:27:26 smsl-n100 volumio[805]: error: Serial API: Failed to decode command: 100, message: undefined Feb 10 14:27:26 smsl-n100 volumio[805]: error: Serial API: Failed to decode command: MAXVOL, message: Feb 10 14:27:26 smsl-n100 volumio[805]: error: Serial API: Failed to decode command: 100, message: undefined Feb 10 14:27:26 smsl-n100 volumio[805]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Feb 10 14:27:27 smsl-n100 volumio[805]: info: CoreStateMachine::ClearQueue Feb 10 14:27:27 smsl-n100 volumio[805]: info: CoreStateMachine::stop Feb 10 14:27:27 smsl-n100 volumio[805]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 10 14:27:27 smsl-n100 volumio[805]: info: CorePlayQueue::clearPlayQueue Feb 10 14:27:27 smsl-n100 volumio[805]: info: CorePlayQueue::saveQueue Feb 10 14:27:27 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushState Feb 10 14:27:27 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushQueue Feb 10 14:27:27 smsl-n100 volumio[805]: info: Feb 10 14:27:27 smsl-n100 volumio[805]: ---------------------------- MPD announces system playlist update Feb 10 14:27:27 smsl-n100 volumio[805]: info: Ignoring MPD Status Update Feb 10 14:27:27 smsl-n100 volumio[805]: error: updateQueue error: null Feb 10 14:27:27 smsl-n100 volumio[805]: info: ------------------------------ 3ms Feb 10 14:27:27 smsl-n100 volumio[805]: info: Clearing queue after UPNP request Feb 10 14:27:27 smsl-n100 volumio[805]: info: CoreStateMachine::ClearQueue Feb 10 14:27:27 smsl-n100 volumio[805]: info: CoreStateMachine::stop Feb 10 14:27:27 smsl-n100 volumio[805]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 10 14:27:27 smsl-n100 volumio[805]: info: CorePlayQueue::clearPlayQueue Feb 10 14:27:27 smsl-n100 volumio[805]: info: CorePlayQueue::saveQueue Feb 10 14:27:27 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushState Feb 10 14:27:27 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushQueue Feb 10 14:27:27 smsl-n100 volumio[805]: info: Feb 10 14:27:27 smsl-n100 volumio[805]: ---------------------------- MPD announces system playlist update Feb 10 14:27:27 smsl-n100 volumio[805]: info: Ignoring MPD Status Update Feb 10 14:27:27 smsl-n100 volumio[805]: error: updateQueue error: null Feb 10 14:27:27 smsl-n100 volumio[805]: info: ------------------------------ 3ms Feb 10 14:27:27 smsl-n100 volumio[805]: info: Clearing queue after UPNP request Feb 10 14:27:27 smsl-n100 volumio[805]: info: CoreStateMachine::ClearQueue Feb 10 14:27:27 smsl-n100 volumio[805]: info: CoreStateMachine::stop Feb 10 14:27:27 smsl-n100 volumio[805]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 10 14:27:27 smsl-n100 volumio[805]: info: CorePlayQueue::clearPlayQueue Feb 10 14:27:27 smsl-n100 volumio[805]: info: CorePlayQueue::saveQueue Feb 10 14:27:27 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushState Feb 10 14:27:27 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushQueue Feb 10 14:27:27 smsl-n100 volumio[805]: info: Feb 10 14:27:27 smsl-n100 volumio[805]: ---------------------------- MPD announces system playlist update Feb 10 14:27:27 smsl-n100 volumio[805]: info: Ignoring MPD Status Update Feb 10 14:27:27 smsl-n100 volumio[805]: error: updateQueue error: null Feb 10 14:27:27 smsl-n100 volumio[805]: info: ------------------------------ 5ms Feb 10 14:27:27 smsl-n100 volumio[805]: info: Starting UPNP Playback Feb 10 14:27:27 smsl-n100 volumio[805]: info: Preparing playback through UPNP Feb 10 14:27:27 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioGetState Feb 10 14:27:27 smsl-n100 volumio[805]: info: CorePlayQueue::getTrack 0 Feb 10 14:27:27 smsl-n100 volumio[805]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 10 14:27:28 smsl-n100 volumio[805]: info: Feb 10 14:27:28 smsl-n100 volumio[805]: ---------------------------- MPD announces system playlist update Feb 10 14:27:28 smsl-n100 volumio[805]: info: Ignoring MPD Status Update Feb 10 14:27:28 smsl-n100 volumio[805]: info: Feb 10 14:27:28 smsl-n100 volumio[805]: ---------------------------- MPD announces system playlist update Feb 10 14:27:28 smsl-n100 volumio[805]: info: Ignoring MPD Status Update Feb 10 14:27:28 smsl-n100 volumio[805]: info: ------------------------------ 5ms Feb 10 14:27:28 smsl-n100 volumio[805]: info: ------------------------------ 4ms Feb 10 14:27:28 smsl-n100 volumio[805]: info: Feb 10 14:27:28 smsl-n100 volumio[805]: ---------------------------- MPD announces system playlist update Feb 10 14:27:28 smsl-n100 volumio[805]: info: Ignoring MPD Status Update Feb 10 14:27:28 smsl-n100 volumio[805]: info: Feb 10 14:27:28 smsl-n100 volumio[805]: ---------------------------- MPD announces system playlist update Feb 10 14:27:28 smsl-n100 volumio[805]: info: Ignoring MPD Status Update Feb 10 14:27:28 smsl-n100 volumio[805]: info: ------------------------------ 5ms Feb 10 14:27:28 smsl-n100 volumio[805]: info: ------------------------------ 4ms Feb 10 14:27:28 smsl-n100 volumio[805]: info: Feb 10 14:27:28 smsl-n100 volumio[805]: ---------------------------- MPD announces system playlist update Feb 10 14:27:28 smsl-n100 volumio[805]: info: Ignoring MPD Status Update Feb 10 14:27:28 smsl-n100 volumio[805]: info: Feb 10 14:27:28 smsl-n100 volumio[805]: ---------------------------- MPD announces system playlist update Feb 10 14:27:28 smsl-n100 volumio[805]: info: Ignoring MPD Status Update Feb 10 14:27:28 smsl-n100 volumio[805]: info: ------------------------------ 5ms Feb 10 14:27:28 smsl-n100 volumio[805]: info: ------------------------------ 4ms Feb 10 14:27:28 smsl-n100 volumio[805]: info: Feb 10 14:27:28 smsl-n100 volumio[805]: ---------------------------- MPD announces state update: player Feb 10 14:27:28 smsl-n100 volumio[805]: info: ControllerMpd::getState Feb 10 14:27:28 smsl-n100 volumio[805]: verbose: ControllerMpd::sendMpdCommand status Feb 10 14:27:28 smsl-n100 volumio[805]: info: Feb 10 14:27:28 smsl-n100 volumio[805]: ---------------------------- MPD announces state update: player Feb 10 14:27:28 smsl-n100 volumio[805]: info: sendMpdCommand status took 10 milliseconds Feb 10 14:27:28 smsl-n100 volumio[805]: info: ControllerMpd::getState Feb 10 14:27:28 smsl-n100 volumio[805]: verbose: ControllerMpd::sendMpdCommand status Feb 10 14:27:28 smsl-n100 volumio[805]: verbose: ControllerMpd::parseState Feb 10 14:27:28 smsl-n100 volumio[805]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 14:27:28 smsl-n100 volumio[805]: info: sendMpdCommand status took 12 milliseconds Feb 10 14:27:28 smsl-n100 volumio[805]: info: sendMpdCommand playlistinfo took 12 milliseconds Feb 10 14:27:28 smsl-n100 volumio[805]: verbose: ControllerMpd::parseState Feb 10 14:27:28 smsl-n100 volumio[805]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 14:27:28 smsl-n100 volumio[805]: verbose: ControllerMpd::parseTrackInfo Feb 10 14:27:28 smsl-n100 volumio[805]: info: ControllerMpd::pushState Feb 10 14:27:28 smsl-n100 volumio[805]: info: CoreCommandRouter::servicePushState Feb 10 14:27:28 smsl-n100 volumio[805]: verbose: In UPNP mode Feb 10 14:27:28 smsl-n100 volumio[805]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":12174,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"foobar2000 audio stream","artist":null,"album":null,"uri":"http://192.168.1.129:49885/42/stream.wav","trackType":"wav"} Feb 10 14:27:28 smsl-n100 volumio[805]: verbose: CURRENT POSITION 0 Feb 10 14:27:28 smsl-n100 volumio[805]: info: CoreStateMachine::syncState stateService play Feb 10 14:27:28 smsl-n100 volumio[805]: info: CoreStateMachine::syncState currentStatus stop Feb 10 14:27:28 smsl-n100 volumio[805]: info: ------------------------------ 42ms Feb 10 14:27:28 smsl-n100 volumio[805]: info: sendMpdCommand playlistinfo took 10 milliseconds Feb 10 14:27:28 smsl-n100 volumio[805]: verbose: ControllerMpd::parseTrackInfo Feb 10 14:27:28 smsl-n100 volumio[805]: info: ControllerMpd::pushState Feb 10 14:27:28 smsl-n100 volumio[805]: info: CoreCommandRouter::servicePushState Feb 10 14:27:28 smsl-n100 volumio[805]: verbose: In UPNP mode Feb 10 14:27:28 smsl-n100 volumio[805]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":12174,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"foobar2000 audio stream","artist":null,"album":null,"uri":"http://192.168.1.129:49885/42/stream.wav","trackType":"wav"} Feb 10 14:27:28 smsl-n100 volumio[805]: verbose: CURRENT POSITION 0 Feb 10 14:27:28 smsl-n100 volumio[805]: info: CoreStateMachine::syncState stateService play Feb 10 14:27:28 smsl-n100 volumio[805]: info: CoreStateMachine::syncState currentStatus play Feb 10 14:27:28 smsl-n100 volumio[805]: info: Received an update from plugin. extracting info from payload Feb 10 14:27:28 smsl-n100 volumio[805]: info: CoreStateMachine::pushState Feb 10 14:27:28 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 14:27:28 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushState Feb 10 14:27:28 smsl-n100 volumio[805]: info: CoreStateMachine::pushState Feb 10 14:27:28 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushState Feb 10 14:27:28 smsl-n100 volumio[805]: info: ------------------------------ 79ms Feb 10 14:27:28 smsl-n100 volumio[805]: info: CoreStateMachine::pushState Feb 10 14:27:28 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushState Feb 10 14:27:28 smsl-n100 volumio[805]: error: Serial API: Failed to decode command: 100, message: undefined Feb 10 14:27:28 smsl-n100 volumio[805]: error: Serial API: Failed to decode command: MAXVOL, message: Feb 10 14:27:28 smsl-n100 volumio[805]: error: Serial API: Failed to decode command: 100, message: undefined Feb 10 14:27:28 smsl-n100 volumio[805]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Feb 10 14:27:55 smsl-n100 sudo[15999]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 10 14:27:55 smsl-n100 sudo[15999]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 14:27:55 smsl-n100 sudo[15999]: pam_unix(sudo:session): session closed for user root Feb 10 14:27:55 smsl-n100 sudo[16001]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 10 14:27:55 smsl-n100 sudo[16001]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 14:27:55 smsl-n100 sudo[16001]: pam_unix(sudo:session): session closed for user root Feb 10 14:27:55 smsl-n100 volumio[805]: verbose: New Socket.io Connection to 192.168.1.130 from 192.168.1.129 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Feb 10 14:27:56 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 10 14:27:56 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioGetVisibleSources Feb 10 14:27:56 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 10 14:27:56 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioGetState Feb 10 14:27:56 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 10 14:27:56 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 10 14:27:56 smsl-n100 volumio[805]: info: Received Get System Info Feb 10 14:27:56 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 10 14:27:56 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 10 14:27:56 smsl-n100 volumio[805]: info: Discovery: Getting this device information Feb 10 14:27:56 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioGetState Feb 10 14:27:56 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 10 14:27:56 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioGetState Feb 10 14:27:56 smsl-n100 volumio[805]: info: Listing playlists Feb 10 14:27:56 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 10 14:27:56 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 10 14:27:56 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 10 14:27:56 smsl-n100 sudo[16006]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 10 14:27:56 smsl-n100 sudo[16006]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 14:27:56 smsl-n100 sudo[16006]: pam_unix(sudo:session): session closed for user root Feb 10 14:27:56 smsl-n100 sudo[16009]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 10 14:27:56 smsl-n100 sudo[16009]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 14:27:56 smsl-n100 sudo[16009]: pam_unix(sudo:session): session closed for user root Feb 10 14:27:56 smsl-n100 volumio[805]: verbose: New Socket.io Connection to 192.168.1.130 from 192.168.1.129 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Feb 10 14:27:56 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 10 14:27:56 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioGetVisibleSources Feb 10 14:27:56 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 10 14:27:56 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioGetState Feb 10 14:27:56 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 10 14:27:56 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 10 14:27:56 smsl-n100 volumio[805]: info: Received Get System Info Feb 10 14:27:56 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 10 14:27:56 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 10 14:27:56 smsl-n100 volumio[805]: info: Discovery: Getting this device information Feb 10 14:27:56 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioGetState Feb 10 14:27:56 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 10 14:27:56 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioGetState Feb 10 14:27:56 smsl-n100 volumio[805]: info: Listing playlists Feb 10 14:27:56 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 10 14:27:56 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 10 14:27:56 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 10 14:27:57 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioGetState Feb 10 14:27:57 smsl-n100 volumio[805]: info: Clearing queue after UPNP request Feb 10 14:27:58 smsl-n100 volumio[805]: info: CoreStateMachine::ClearQueue Feb 10 14:27:58 smsl-n100 volumio[805]: info: CoreStateMachine::stop Feb 10 14:27:58 smsl-n100 volumio[805]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 10 14:27:58 smsl-n100 volumio[805]: info: CoreStateMachine::stPlaybackTimer Feb 10 14:27:58 smsl-n100 volumio[805]: info: CoreStateMachine::updateTrackBlock Feb 10 14:27:58 smsl-n100 volumio[805]: info: CorePlayQueue::getTrackBlock Feb 10 14:27:58 smsl-n100 volumio[805]: info: CoreStateMachine::pushState Feb 10 14:27:58 smsl-n100 volumio[805]: info: CorePlayQueue::getTrack 0 Feb 10 14:27:58 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 14:27:58 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushState Feb 10 14:27:58 smsl-n100 volumio[805]: info: CoreStateMachine::serviceStop Feb 10 14:27:58 smsl-n100 volumio[805]: info: CorePlayQueue::getTrack 0 Feb 10 14:27:58 smsl-n100 volumio[805]: info: ControllerMpd::stop Feb 10 14:27:58 smsl-n100 volumio[805]: verbose: ControllerMpd::sendMpdCommand stop Feb 10 14:27:58 smsl-n100 volumio[805]: info: CorePlayQueue::clearPlayQueue Feb 10 14:27:58 smsl-n100 volumio[805]: info: CorePlayQueue::saveQueue Feb 10 14:27:58 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushState Feb 10 14:27:58 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushQueue Feb 10 14:27:58 smsl-n100 volumio[805]: info: Feb 10 14:27:58 smsl-n100 volumio[805]: ---------------------------- MPD announces state update: player Feb 10 14:27:58 smsl-n100 volumio[805]: info: Feb 10 14:27:58 smsl-n100 volumio[805]: ---------------------------- MPD announces system playlist update Feb 10 14:27:58 smsl-n100 volumio[805]: info: Ignoring MPD Status Update Feb 10 14:27:58 smsl-n100 volumio[805]: info: ControllerMpd::getState Feb 10 14:27:58 smsl-n100 volumio[805]: verbose: ControllerMpd::sendMpdCommand status Feb 10 14:27:58 smsl-n100 volumio[805]: info: sendMpdCommand stop took 46 milliseconds Feb 10 14:27:58 smsl-n100 volumio[805]: error: updateQueue error: null Feb 10 14:27:58 smsl-n100 volumio[805]: info: sendMpdCommand status took 5 milliseconds Feb 10 14:27:58 smsl-n100 volumio[805]: info: ------------------------------ 9ms Feb 10 14:27:58 smsl-n100 volumio[805]: verbose: ControllerMpd::parseState Feb 10 14:27:58 smsl-n100 volumio[805]: info: ControllerMpd::pushState Feb 10 14:27:58 smsl-n100 volumio[805]: info: CoreCommandRouter::servicePushState Feb 10 14:27:58 smsl-n100 volumio[805]: info: CoreStateMachine::pushState Feb 10 14:27:58 smsl-n100 volumio[805]: info: CorePlayQueue::getTrack 0 Feb 10 14:27:58 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushState Feb 10 14:27:58 smsl-n100 volumio[805]: info: CorePlayQueue::getTrack 0 Feb 10 14:27:58 smsl-n100 volumio[805]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 10 14:27:58 smsl-n100 volumio[805]: verbose: CURRENT POSITION 0 Feb 10 14:27:58 smsl-n100 volumio[805]: info: CoreStateMachine::syncState stateService stop Feb 10 14:27:58 smsl-n100 volumio[805]: info: CoreStateMachine::syncState currentStatus stop Feb 10 14:27:58 smsl-n100 volumio[805]: info: CoreStateMachine::pushState Feb 10 14:27:58 smsl-n100 volumio[805]: info: CorePlayQueue::getTrack 0 Feb 10 14:27:58 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushState Feb 10 14:27:58 smsl-n100 volumio[805]: info: No code Feb 10 14:27:58 smsl-n100 volumio[805]: info: CoreStateMachine::pushState Feb 10 14:27:58 smsl-n100 volumio[805]: info: CorePlayQueue::getTrack 0 Feb 10 14:27:58 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushState Feb 10 14:27:58 smsl-n100 volumio[805]: info: ------------------------------ 47ms Feb 10 14:27:58 smsl-n100 volumio[805]: info: CoreStateMachine::pushState Feb 10 14:27:58 smsl-n100 volumio[805]: info: CorePlayQueue::getTrack 0 Feb 10 14:27:58 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushState Feb 10 14:27:58 smsl-n100 volumio[805]: error: Serial API: Failed to decode command: 100, message: undefined Feb 10 14:27:58 smsl-n100 volumio[805]: error: Serial API: Failed to decode command: MAXVOL, message: Feb 10 14:27:58 smsl-n100 volumio[805]: error: Serial API: Failed to decode command: 100, message: undefined Feb 10 14:27:58 smsl-n100 volumio[805]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Feb 10 14:27:58 smsl-n100 volumio[805]: info: VolumeController::SetAlsaVolume0 Feb 10 14:27:58 smsl-n100 volumio[805]: info: CoreStateMachine::pushState Feb 10 14:27:58 smsl-n100 volumio[805]: info: CorePlayQueue::getTrack 0 Feb 10 14:27:58 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 14:27:58 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioPushState Feb 10 14:27:58 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Feb 10 14:27:58 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 10 14:27:58 smsl-n100 volumio[805]: info: Received Get System Info Feb 10 14:27:58 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 10 14:27:58 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 10 14:27:58 smsl-n100 volumio[805]: info: Discovery: Getting this device information Feb 10 14:27:58 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioGetState Feb 10 14:27:58 smsl-n100 volumio[805]: info: CorePlayQueue::getTrack 0 Feb 10 14:27:58 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 10 14:27:58 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 10 14:28:01 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 10 14:28:01 smsl-n100 volumio[805]: info: Received Get System Info Feb 10 14:28:01 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 10 14:28:01 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 10 14:28:01 smsl-n100 volumio[805]: info: Discovery: Getting this device information Feb 10 14:28:01 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioGetState Feb 10 14:28:01 smsl-n100 volumio[805]: info: CorePlayQueue::getTrack 0 Feb 10 14:28:01 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 10 14:28:05 smsl-n100 volumio[805]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 10 14:28:05 smsl-n100 volumio[805]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 10 14:28:05 smsl-n100 volumio[805]: info: Retrieving Cloud Streaming UI Feb 10 14:28:05 smsl-n100 volumio[805]: info: Getting Tidal Cloud Configuration Feb 10 14:28:05 smsl-n100 volumio[805]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 10 14:28:05 smsl-n100 volumio[805]: info: Getting Qobuz Cloud Configuration Feb 10 14:28:05 smsl-n100 volumio[805]: info: Asking plugin for UI Config Feb 10 14:28:05 smsl-n100 volumio[805]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 10 14:28:05 smsl-n100 volumio[805]: info: Getting Spotify Cloud Configuration Feb 10 14:28:05 smsl-n100 volumio[805]: info: Asking plugin for UI Config Feb 10 14:28:05 smsl-n100 volumio[805]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 10 14:28:05 smsl-n100 volumio[805]: info: Saving Spotify Acccount Feb 10 14:28:05 smsl-n100 volumio[805]: info: Got it Feb 10 14:28:05 smsl-n100 volumio[805]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Feb 10 14:28:05 smsl-n100 volumio[805]: info: Got Tidal Cloud Configuration Feb 10 14:28:05 smsl-n100 volumio[805]: info: Got it Feb 10 14:28:05 smsl-n100 volumio[805]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 10 14:28:05 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 10 14:28:05 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 10 14:28:05 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 10 14:28:05 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 10 14:28:05 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 10 14:28:05 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 10 14:28:05 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 10 14:28:05 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 10 14:28:05 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioGetBrowseSources Feb 10 14:28:05 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioGetBrowseSources Feb 10 14:28:05 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioGetBrowseSources Feb 10 14:28:05 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 10 14:28:05 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Feb 10 14:28:06 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 10 14:28:09 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 10 14:28:12 smsl-n100 volumio[805]: info: Enabling MyMusic plugin metavolumio Feb 10 14:28:12 smsl-n100 volumio[805]: info: Enabling plugin metavolumio Feb 10 14:28:12 smsl-n100 volumio[805]: info: Loading plugin "metavolumio"... Feb 10 14:28:15 smsl-n100 volumio[805]: info: PLUGIN START: metavolumio Feb 10 14:28:15 smsl-n100 volumio[805]: info: Adding METAVOLUMIO REST API Endpoints Feb 10 14:28:15 smsl-n100 volumio[805]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Feb 10 14:28:15 smsl-n100 volumio[805]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Feb 10 14:28:15 smsl-n100 volumio[805]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Feb 10 14:28:15 smsl-n100 volumio[805]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Feb 10 14:28:15 smsl-n100 volumio[805]: info: Done. Feb 10 14:28:15 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 10 14:28:15 smsl-n100 volumio[805]: info: Disabling MyMusic plugin upnp Feb 10 14:28:15 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 10 14:28:15 smsl-n100 volumio[805]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 10 14:28:15 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 10 14:28:15 smsl-n100 volumio[805]: info: Received Get System Version Feb 10 14:28:15 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 10 14:28:16 smsl-n100 volumio[805]: info: Received Get System Info Feb 10 14:28:16 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 10 14:28:16 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 10 14:28:16 smsl-n100 volumio[805]: info: Discovery: Getting this device information Feb 10 14:28:16 smsl-n100 volumio[805]: info: CoreCommandRouter::volumioGetState Feb 10 14:28:16 smsl-n100 volumio[805]: info: CorePlayQueue::getTrack 0 Feb 10 14:28:16 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 10 14:28:16 smsl-n100 sudo[16089]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Feb 10 14:28:16 smsl-n100 sudo[16089]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 14:28:16 smsl-n100 volumio[805]: error: Upnp client error: Error: This socket has been ended by the other party Feb 10 14:28:16 smsl-n100 systemd[1]: Stopping UPnP Renderer front-end to MPD... Feb 10 14:28:17 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 10 14:28:19 smsl-n100 volumio[805]: info: Disabling MyMusic plugin upnp Feb 10 14:28:19 smsl-n100 sudo[16106]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Feb 10 14:28:19 smsl-n100 sudo[16106]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 14:28:21 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 10 14:28:24 smsl-n100 volumio[805]: info: Disabling MyMusic plugin metavolumio Feb 10 14:28:24 smsl-n100 volumio[805]: info: Removing METAVOLUMIO REST API Endpoints Feb 10 14:28:24 smsl-n100 volumio[805]: info: Removing metavolumio REST Endpoint Feb 10 14:28:24 smsl-n100 volumio[805]: info: Removing getSimilarArtists REST Endpoint Feb 10 14:28:24 smsl-n100 volumio[805]: info: Removing getSimilarAlbums REST Endpoint Feb 10 14:28:24 smsl-n100 volumio[805]: info: Removing getSimilarTracks REST Endpoint Feb 10 14:28:24 smsl-n100 volumio[805]: info: Disabling plugin metavolumio Feb 10 14:28:24 smsl-n100 volumio[805]: info: Done. Feb 10 14:28:25 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 10 14:28:29 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 10 14:28:33 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 10 14:28:37 smsl-n100 volumio[805]: info: Disabling MyMusic plugin upnp Feb 10 14:28:37 smsl-n100 sudo[16127]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Feb 10 14:28:37 smsl-n100 sudo[16127]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 14:28:37 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 10 14:28:40 smsl-n100 volumio[805]: info: Enabling MyMusic plugin upnp Feb 10 14:28:40 smsl-n100 volumio[805]: info: Enabling plugin upnp Feb 10 14:28:40 smsl-n100 volumio[805]: info: Loading plugin "upnp"... Feb 10 14:28:40 smsl-n100 volumio[805]: info: [1770730120294] Starting Upmpd Daemon Feb 10 14:28:40 smsl-n100 volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 10 14:28:40 smsl-n100 volumio[805]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 10 14:28:40 smsl-n100 volumio[805]: Error: listen EADDRINUSE: address already in use :::6599 Feb 10 14:28:40 smsl-n100 volumio[805]: at Server.setupListenHandle [as _listen2] (net.js:1331:16) Feb 10 14:28:40 smsl-n100 volumio[805]: at listenInCluster (net.js:1379:12) Feb 10 14:28:40 smsl-n100 volumio[805]: at Server.listen (net.js:1465:7) Feb 10 14:28:40 smsl-n100 volumio[805]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Feb 10 14:28:40 smsl-n100 volumio[805]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Feb 10 14:28:40 smsl-n100 volumio[805]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Feb 10 14:28:40 smsl-n100 volumio[805]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Feb 10 14:28:40 smsl-n100 volumio[805]: at processTicksAndRejections (internal/process/task_queues.js:77:11) { Feb 10 14:28:40 smsl-n100 volumio[805]: code: 'EADDRINUSE', Feb 10 14:28:40 smsl-n100 volumio[805]: errno: -98, Feb 10 14:28:40 smsl-n100 volumio[805]: syscall: 'listen', Feb 10 14:28:40 smsl-n100 volumio[805]: address: '::', Feb 10 14:28:40 smsl-n100 volumio[805]: port: 6599 Feb 10 14:28:40 smsl-n100 volumio[805]: } Feb 10 14:28:40 smsl-n100 volumio[805]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 10 14:28:41 smsl-n100 sudo[16155]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-10 14:27 Feb 10 14:28:41 smsl-n100 sudo[16155]: 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="cc50ad4a2058d01de272214eb33827883bd2b7d8" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="3441cddd43405c36fe444484553f10f2f5bc830b" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="smsl" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 13 Nov 2025 12:39:08 PM CET" VOLUMIO_VERSION="3.874" VOLUMIO_HARDWARE="nanopineo3" VOLUMIO_DEVICENAME="Nanopi Neo3" VOLUMIO_VENDOR_MODEL="SMSL S100" VOLUMIO_VENDOR="SMSL" VOLUMIO_MODEL="S100" VOLUMIO_HASH="7a92d73f7a04bc7854b1812143743fd4"