-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Sat 2025-10-25 11:41:00 CEST. -- Oct 25 11:40:20 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Oct 25 11:40:20 volumio volumio[793]: info: CorePlayQueue::getTrack 1 Oct 25 11:40:20 volumio volumio[793]: info: Prefetching next song Oct 25 11:40:20 volumio volumio[793]: info: [1761385220216] ControllerQobuz::prefetch Oct 25 11:40:20 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/67587370" Oct 25 11:40:20 volumio volumio[793]: STREAMING PROXY: Handling url /?data=qobuz://song/67587370 Oct 25 11:40:20 volumio volumio[793]: info: Executing endpoint getStreamUrlqobuz Oct 25 11:40:20 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Oct 25 11:40:20 volumio volumio[793]: info: getStreamUrl took 229 milliseconds Oct 25 11:40:20 volumio volumio[793]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=2125693&eid=67587370&fmt=6&profile=raw&app_id=539451548&cid=2454943&etsp=1761388820&hmac=TdQaTcUJZpaskuLt33LY_HChfBM Oct 25 11:40:21 volumio volumio[793]: STREAMING PROXY: Response: 200, length: 7447163 Oct 25 11:40:21 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/67587370" Oct 25 11:40:21 volumio volumio[793]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/67587370" took 2 milliseconds Oct 25 11:40:21 volumio volumio[793]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 25 11:40:21 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand consume 1 Oct 25 11:40:21 volumio volumio[793]: info: Oct 25 11:40:21 volumio volumio[793]: ---------------------------- MPD announces system playlist update Oct 25 11:40:21 volumio volumio[793]: info: Ignoring MPD Status Update Oct 25 11:40:21 volumio volumio[793]: info: Oct 25 11:40:21 volumio volumio[793]: ---------------------------- MPD announces state update: options Oct 25 11:40:21 volumio volumio[793]: info: sendMpdCommand consume 1 took 4 milliseconds Oct 25 11:40:21 volumio volumio[793]: info: ControllerMpd::getState Oct 25 11:40:21 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand status Oct 25 11:40:21 volumio volumio[793]: STREAMING PROXY: Client dropped request, destroying Oct 25 11:40:21 volumio volumio[793]: info: Oct 25 11:40:21 volumio volumio[793]: ---------------------------- MPD announces system playlist update Oct 25 11:40:21 volumio volumio[793]: info: Ignoring MPD Status Update Oct 25 11:40:21 volumio volumio[793]: info: Oct 25 11:40:21 volumio volumio[793]: ---------------------------- MPD announces system playlist update Oct 25 11:40:21 volumio volumio[793]: info: Ignoring MPD Status Update Oct 25 11:40:21 volumio volumio[793]: info: Oct 25 11:40:21 volumio volumio[793]: ---------------------------- MPD announces system playlist update Oct 25 11:40:21 volumio volumio[793]: info: Ignoring MPD Status Update Oct 25 11:40:21 volumio volumio[793]: info: Oct 25 11:40:21 volumio volumio[793]: ---------------------------- MPD announces system playlist update Oct 25 11:40:21 volumio volumio[793]: info: Ignoring MPD Status Update Oct 25 11:40:21 volumio volumio[793]: info: Oct 25 11:40:21 volumio volumio[793]: ---------------------------- MPD announces system playlist update Oct 25 11:40:21 volumio volumio[793]: info: Ignoring MPD Status Update Oct 25 11:40:21 volumio volumio[793]: info: Oct 25 11:40:21 volumio volumio[793]: ---------------------------- MPD announces system playlist update Oct 25 11:40:21 volumio volumio[793]: info: Ignoring MPD Status Update Oct 25 11:40:21 volumio volumio[793]: info: Oct 25 11:40:21 volumio volumio[793]: ---------------------------- MPD announces system playlist update Oct 25 11:40:21 volumio volumio[793]: info: Ignoring MPD Status Update Oct 25 11:40:21 volumio volumio[793]: info: Oct 25 11:40:21 volumio volumio[793]: ---------------------------- MPD announces system playlist update Oct 25 11:40:21 volumio volumio[793]: info: Ignoring MPD Status Update Oct 25 11:40:21 volumio volumio[793]: info: Oct 25 11:40:21 volumio volumio[793]: ---------------------------- MPD announces system playlist update Oct 25 11:40:21 volumio volumio[793]: info: Ignoring MPD Status Update Oct 25 11:40:21 volumio volumio[793]: info: Oct 25 11:40:21 volumio volumio[793]: ---------------------------- MPD announces system playlist update Oct 25 11:40:21 volumio volumio[793]: info: Ignoring MPD Status Update Oct 25 11:40:21 volumio volumio[793]: info: ------------------------------ 25ms Oct 25 11:40:21 volumio volumio[793]: info: sendMpdCommand status took 21 milliseconds Oct 25 11:40:21 volumio volumio[793]: info: ------------------------------ 21ms Oct 25 11:40:21 volumio volumio[793]: info: ------------------------------ 19ms Oct 25 11:40:21 volumio volumio[793]: info: ------------------------------ 18ms Oct 25 11:40:21 volumio volumio[793]: info: ------------------------------ 16ms Oct 25 11:40:21 volumio volumio[793]: info: ------------------------------ 14ms Oct 25 11:40:21 volumio volumio[793]: info: ------------------------------ 13ms Oct 25 11:40:21 volumio volumio[793]: info: ------------------------------ 12ms Oct 25 11:40:21 volumio volumio[793]: info: ------------------------------ 11ms Oct 25 11:40:21 volumio volumio[793]: info: ------------------------------ 9ms Oct 25 11:40:21 volumio volumio[793]: info: ------------------------------ 8ms Oct 25 11:40:21 volumio volumio[793]: verbose: ControllerMpd::parseState Oct 25 11:40:21 volumio volumio[793]: info: ControllerMpd::pushState Oct 25 11:40:21 volumio volumio[793]: info: CoreCommandRouter::servicePushState Oct 25 11:40:21 volumio volumio[793]: info: CoreStateMachine::pushState Oct 25 11:40:21 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 11:40:21 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Oct 25 11:40:21 volumio volumio[793]: info: MRS: Pushing multiroomSync output update for this device Oct 25 11:40:21 volumio volumio[793]: info: MRS: Pushing multiroomSync output Oct 25 11:40:21 volumio volumio[793]: info: CoreCommandRouter::volumioGetState Oct 25 11:40:21 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Oct 25 11:40:21 volumio volumio[793]: 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} Oct 25 11:40:21 volumio volumio[793]: verbose: CURRENT POSITION 0 Oct 25 11:40:21 volumio volumio[793]: info: CoreStateMachine::syncState stateService stop Oct 25 11:40:21 volumio volumio[793]: info: CoreStateMachine::syncState currentStatus stop Oct 25 11:40:21 volumio volumio[793]: info: CoreStateMachine::pushState Oct 25 11:40:21 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Oct 25 11:40:21 volumio volumio[793]: info: MRS: Pushing multiroomSync output update for this device Oct 25 11:40:21 volumio volumio[793]: info: MRS: Pushing multiroomSync output Oct 25 11:40:21 volumio volumio[793]: info: CoreCommandRouter::volumioGetState Oct 25 11:40:21 volumio volumio[793]: info: No code Oct 25 11:40:21 volumio volumio[793]: info: CoreStateMachine::pushState Oct 25 11:40:21 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Oct 25 11:40:21 volumio volumio[793]: info: MRS: Pushing multiroomSync output update for this device Oct 25 11:40:21 volumio volumio[793]: info: MRS: Pushing multiroomSync output Oct 25 11:40:21 volumio volumio[793]: info: CoreCommandRouter::volumioGetState Oct 25 11:40:21 volumio volumio[793]: info: ------------------------------ 91ms Oct 25 11:40:21 volumio volumio[793]: info: Oct 25 11:40:21 volumio volumio[793]: ---------------------------- MPD announces state update: options Oct 25 11:40:21 volumio volumio[793]: info: ControllerMpd::getState Oct 25 11:40:21 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand status Oct 25 11:40:21 volumio volumio[793]: info: Oct 25 11:40:21 volumio volumio[793]: ---------------------------- MPD announces state update: options Oct 25 11:40:21 volumio volumio[793]: info: ControllerMpd::getState Oct 25 11:40:21 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand status Oct 25 11:40:21 volumio volumio[793]: info: Oct 25 11:40:21 volumio volumio[793]: ---------------------------- MPD announces state update: options Oct 25 11:40:21 volumio volumio[793]: info: ControllerMpd::getState Oct 25 11:40:21 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand status Oct 25 11:40:21 volumio volumio[793]: info: Oct 25 11:40:21 volumio volumio[793]: ---------------------------- MPD announces state update: options Oct 25 11:40:21 volumio volumio[793]: info: ControllerMpd::getState Oct 25 11:40:21 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand status Oct 25 11:40:21 volumio volumio[793]: info: Oct 25 11:40:21 volumio volumio[793]: ---------------------------- MPD announces state update: options Oct 25 11:40:21 volumio volumio[793]: info: ControllerMpd::getState Oct 25 11:40:21 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand status Oct 25 11:40:21 volumio volumio[793]: info: Oct 25 11:40:21 volumio volumio[793]: ---------------------------- MPD announces state update: options Oct 25 11:40:21 volumio volumio[793]: info: ControllerMpd::getState Oct 25 11:40:21 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand status Oct 25 11:40:21 volumio volumio[793]: info: Oct 25 11:40:21 volumio volumio[793]: ---------------------------- MPD announces state update: options Oct 25 11:40:21 volumio volumio[793]: info: ControllerMpd::getState Oct 25 11:40:21 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand status Oct 25 11:40:21 volumio volumio[793]: info: Oct 25 11:40:21 volumio volumio[793]: ---------------------------- MPD announces state update: options Oct 25 11:40:21 volumio volumio[793]: info: ControllerMpd::getState Oct 25 11:40:21 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand status Oct 25 11:40:21 volumio volumio[793]: info: Oct 25 11:40:21 volumio volumio[793]: ---------------------------- MPD announces state update: options Oct 25 11:40:21 volumio volumio[793]: info: ControllerMpd::getState Oct 25 11:40:21 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand status Oct 25 11:40:21 volumio volumio[793]: info: Oct 25 11:40:21 volumio volumio[793]: ---------------------------- MPD announces state update: options Oct 25 11:40:21 volumio volumio[793]: info: ControllerMpd::getState Oct 25 11:40:21 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand status Oct 25 11:40:21 volumio volumio[793]: info: sendMpdCommand status took 14 milliseconds Oct 25 11:40:21 volumio volumio[793]: info: sendMpdCommand status took 13 milliseconds Oct 25 11:40:21 volumio volumio[793]: info: sendMpdCommand status took 12 milliseconds Oct 25 11:40:21 volumio volumio[793]: info: sendMpdCommand status took 11 milliseconds Oct 25 11:40:21 volumio volumio[793]: info: sendMpdCommand status took 9 milliseconds Oct 25 11:40:21 volumio volumio[793]: info: sendMpdCommand status took 8 milliseconds Oct 25 11:40:21 volumio volumio[793]: info: sendMpdCommand status took 8 milliseconds Oct 25 11:40:21 volumio volumio[793]: info: sendMpdCommand status took 6 milliseconds Oct 25 11:40:21 volumio volumio[793]: info: sendMpdCommand status took 5 milliseconds Oct 25 11:40:21 volumio volumio[793]: info: sendMpdCommand status took 4 milliseconds Oct 25 11:40:21 volumio volumio[793]: verbose: ControllerMpd::parseState Oct 25 11:40:21 volumio volumio[793]: verbose: ControllerMpd::parseState Oct 25 11:40:21 volumio volumio[793]: verbose: ControllerMpd::parseState Oct 25 11:40:21 volumio volumio[793]: verbose: ControllerMpd::parseState Oct 25 11:40:21 volumio volumio[793]: verbose: ControllerMpd::parseState Oct 25 11:40:21 volumio volumio[793]: verbose: ControllerMpd::parseState Oct 25 11:40:21 volumio volumio[793]: verbose: ControllerMpd::parseState Oct 25 11:40:21 volumio volumio[793]: verbose: ControllerMpd::parseState Oct 25 11:40:21 volumio volumio[793]: verbose: ControllerMpd::parseState Oct 25 11:40:21 volumio volumio[793]: verbose: ControllerMpd::parseState Oct 25 11:40:21 volumio volumio[793]: info: ControllerMpd::pushState Oct 25 11:40:21 volumio volumio[793]: info: CoreCommandRouter::servicePushState Oct 25 11:40:21 volumio volumio[793]: info: CoreStateMachine::pushState Oct 25 11:40:21 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Oct 25 11:40:21 volumio volumio[793]: info: MRS: Pushing multiroomSync output update for this device Oct 25 11:40:21 volumio volumio[793]: info: MRS: Pushing multiroomSync output Oct 25 11:40:21 volumio volumio[793]: info: CoreCommandRouter::volumioGetState Oct 25 11:40:21 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Oct 25 11:40:21 volumio volumio[793]: 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} Oct 25 11:40:21 volumio volumio[793]: verbose: CURRENT POSITION 0 Oct 25 11:40:21 volumio volumio[793]: info: CoreStateMachine::syncState stateService stop Oct 25 11:40:21 volumio volumio[793]: info: CoreStateMachine::syncState currentStatus stop Oct 25 11:40:21 volumio volumio[793]: info: CoreStateMachine::pushState Oct 25 11:40:21 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Oct 25 11:40:21 volumio volumio[793]: info: MRS: Pushing multiroomSync output update for this device Oct 25 11:40:21 volumio volumio[793]: info: MRS: Pushing multiroomSync output Oct 25 11:40:21 volumio volumio[793]: info: CoreCommandRouter::volumioGetState Oct 25 11:40:21 volumio volumio[793]: info: No code Oct 25 11:40:21 volumio volumio[793]: info: CoreStateMachine::pushState Oct 25 11:40:21 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Oct 25 11:40:21 volumio volumio[793]: info: MRS: Pushing multiroomSync output update for this device Oct 25 11:40:21 volumio volumio[793]: info: MRS: Pushing multiroomSync output Oct 25 11:40:21 volumio volumio[793]: info: CoreCommandRouter::volumioGetState Oct 25 11:40:21 volumio volumio[793]: info: ControllerMpd::pushState Oct 25 11:40:21 volumio volumio[793]: info: CoreCommandRouter::servicePushState Oct 25 11:40:21 volumio volumio[793]: info: CoreStateMachine::pushState Oct 25 11:40:21 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Oct 25 11:40:21 volumio volumio[793]: info: MRS: Pushing multiroomSync output update for this device Oct 25 11:40:21 volumio volumio[793]: info: MRS: Pushing multiroomSync output Oct 25 11:40:21 volumio volumio[793]: info: CoreCommandRouter::volumioGetState Oct 25 11:40:21 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Oct 25 11:40:21 volumio volumio[793]: 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} Oct 25 11:40:21 volumio volumio[793]: verbose: CURRENT POSITION 0 Oct 25 11:40:21 volumio volumio[793]: info: CoreStateMachine::syncState stateService stop Oct 25 11:40:21 volumio volumio[793]: info: CoreStateMachine::syncState currentStatus stop Oct 25 11:40:21 volumio volumio[793]: info: CoreStateMachine::pushState Oct 25 11:40:21 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Oct 25 11:40:21 volumio volumio[793]: info: MRS: Pushing multiroomSync output update for this device Oct 25 11:40:21 volumio volumio[793]: info: MRS: Pushing multiroomSync output Oct 25 11:40:21 volumio volumio[793]: info: CoreCommandRouter::volumioGetState Oct 25 11:40:21 volumio volumio[793]: info: No code Oct 25 11:40:21 volumio volumio[793]: info: CoreStateMachine::pushState Oct 25 11:40:21 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Oct 25 11:40:21 volumio volumio[793]: info: MRS: Pushing multiroomSync output update for this device Oct 25 11:40:21 volumio volumio[793]: info: MRS: Pushing multiroomSync output Oct 25 11:40:21 volumio volumio[793]: info: CoreCommandRouter::volumioGetState Oct 25 11:40:21 volumio volumio[793]: info: ControllerMpd::pushState Oct 25 11:40:21 volumio volumio[793]: info: CoreCommandRouter::servicePushState Oct 25 11:40:21 volumio volumio[793]: info: CoreStateMachine::pushState Oct 25 11:40:21 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Oct 25 11:40:21 volumio volumio[793]: info: MRS: Pushing multiroomSync output update for this device Oct 25 11:40:21 volumio volumio[793]: info: MRS: Pushing multiroomSync output Oct 25 11:40:21 volumio volumio[793]: info: CoreCommandRouter::volumioGetState Oct 25 11:40:21 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Oct 25 11:40:21 volumio volumio[793]: 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} Oct 25 11:40:21 volumio volumio[793]: verbose: CURRENT POSITION 0 Oct 25 11:40:21 volumio volumio[793]: info: CoreStateMachine::syncState stateService stop Oct 25 11:40:21 volumio volumio[793]: info: CoreStateMachine::syncState currentStatus stop Oct 25 11:40:21 volumio volumio[793]: info: CoreStateMachine::pushState Oct 25 11:40:21 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Oct 25 11:40:21 volumio volumio[793]: info: MRS: Pushing multiroomSync output update for this device Oct 25 11:40:21 volumio volumio[793]: info: MRS: Pushing multiroomSync output Oct 25 11:40:21 volumio volumio[793]: info: CoreCommandRouter::volumioGetState Oct 25 11:40:21 volumio volumio[793]: info: No code Oct 25 11:40:21 volumio volumio[793]: info: CoreStateMachine::pushState Oct 25 11:40:21 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Oct 25 11:40:21 volumio volumio[793]: info: MRS: Pushing multiroomSync output update for this device Oct 25 11:40:21 volumio volumio[793]: info: MRS: Pushing multiroomSync output Oct 25 11:40:21 volumio volumio[793]: info: CoreCommandRouter::volumioGetState Oct 25 11:40:21 volumio volumio[793]: info: ControllerMpd::pushState Oct 25 11:40:21 volumio volumio[793]: info: CoreCommandRouter::servicePushState Oct 25 11:40:21 volumio volumio[793]: info: CoreStateMachine::pushState Oct 25 11:40:21 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Oct 25 11:40:21 volumio volumio[793]: info: MRS: Pushing multiroomSync output update for this device Oct 25 11:40:21 volumio volumio[793]: info: MRS: Pushing multiroomSync output Oct 25 11:40:21 volumio volumio[793]: info: CoreCommandRouter::volumioGetState Oct 25 11:40:21 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Oct 25 11:40:21 volumio volumio[793]: 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} Oct 25 11:40:21 volumio volumio[793]: verbose: CURRENT POSITION 0 Oct 25 11:40:21 volumio volumio[793]: info: CoreStateMachine::syncState stateService stop Oct 25 11:40:21 volumio volumio[793]: info: CoreStateMachine::syncState currentStatus stop Oct 25 11:40:21 volumio volumio[793]: info: CoreStateMachine::pushState Oct 25 11:40:21 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Oct 25 11:40:21 volumio volumio[793]: info: MRS: Pushing multiroomSync output update for this device Oct 25 11:40:21 volumio volumio[793]: info: MRS: Pushing multiroomSync output Oct 25 11:40:21 volumio volumio[793]: info: CoreCommandRouter::volumioGetState Oct 25 11:40:21 volumio volumio[793]: info: No code Oct 25 11:40:21 volumio volumio[793]: info: CoreStateMachine::pushState Oct 25 11:40:21 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Oct 25 11:40:21 volumio volumio[793]: info: MRS: Pushing multiroomSync output update for this device Oct 25 11:40:21 volumio volumio[793]: info: MRS: Pushing multiroomSync output Oct 25 11:40:21 volumio volumio[793]: info: CoreCommandRouter::volumioGetState Oct 25 11:40:21 volumio volumio[793]: info: ControllerMpd::pushState Oct 25 11:40:21 volumio volumio[793]: info: CoreCommandRouter::servicePushState Oct 25 11:40:21 volumio volumio[793]: info: CoreStateMachine::pushState Oct 25 11:40:21 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Oct 25 11:40:21 volumio volumio[793]: info: MRS: Pushing multiroomSync output update for this device Oct 25 11:40:21 volumio volumio[793]: info: MRS: Pushing multiroomSync output Oct 25 11:40:21 volumio volumio[793]: info: CoreCommandRouter::volumioGetState Oct 25 11:40:21 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Oct 25 11:40:21 volumio volumio[793]: 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} Oct 25 11:40:21 volumio volumio[793]: verbose: CURRENT POSITION 0 Oct 25 11:40:21 volumio volumio[793]: info: CoreStateMachine::syncState stateService stop Oct 25 11:40:21 volumio volumio[793]: info: CoreStateMachine::syncState currentStatus stop Oct 25 11:40:21 volumio volumio[793]: info: CoreStateMachine::pushState Oct 25 11:40:21 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Oct 25 11:40:21 volumio volumio[793]: info: MRS: Pushing multiroomSync output update for this device Oct 25 11:40:21 volumio volumio[793]: info: MRS: Pushing multiroomSync output Oct 25 11:40:21 volumio volumio[793]: info: CoreCommandRouter::volumioGetState Oct 25 11:40:21 volumio volumio[793]: info: No code Oct 25 11:40:21 volumio volumio[793]: info: CoreStateMachine::pushState Oct 25 11:40:21 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Oct 25 11:40:21 volumio volumio[793]: info: MRS: Pushing multiroomSync output update for this device Oct 25 11:40:21 volumio volumio[793]: info: MRS: Pushing multiroomSync output Oct 25 11:40:21 volumio volumio[793]: info: CoreCommandRouter::volumioGetState Oct 25 11:40:21 volumio volumio[793]: info: ControllerMpd::pushState Oct 25 11:40:21 volumio volumio[793]: info: CoreCommandRouter::servicePushState Oct 25 11:40:21 volumio volumio[793]: info: CoreStateMachine::pushState Oct 25 11:40:21 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Oct 25 11:40:21 volumio volumio[793]: info: MRS: Pushing multiroomSync output update for this device Oct 25 11:40:21 volumio volumio[793]: info: MRS: Pushing multiroomSync output Oct 25 11:40:21 volumio volumio[793]: info: CoreCommandRouter::volumioGetState Oct 25 11:40:21 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Oct 25 11:40:21 volumio volumio[793]: 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} Oct 25 11:40:21 volumio volumio[793]: verbose: CURRENT POSITION 0 Oct 25 11:40:21 volumio volumio[793]: info: CoreStateMachine::syncState stateService stop Oct 25 11:40:21 volumio volumio[793]: info: CoreStateMachine::syncState currentStatus stop Oct 25 11:40:21 volumio volumio[793]: info: CoreStateMachine::pushState Oct 25 11:40:21 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Oct 25 11:40:21 volumio volumio[793]: info: MRS: Pushing multiroomSync output update for this device Oct 25 11:40:21 volumio volumio[793]: info: MRS: Pushing multiroomSync output Oct 25 11:40:21 volumio volumio[793]: info: CoreCommandRouter::volumioGetState Oct 25 11:40:21 volumio volumio[793]: info: No code Oct 25 11:40:21 volumio volumio[793]: info: CoreStateMachine::pushState Oct 25 11:40:21 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Oct 25 11:40:21 volumio volumio[793]: info: MRS: Pushing multiroomSync output update for this device Oct 25 11:40:21 volumio volumio[793]: info: MRS: Pushing multiroomSync output Oct 25 11:40:21 volumio volumio[793]: info: CoreCommandRouter::volumioGetState Oct 25 11:40:21 volumio volumio[793]: info: ControllerMpd::pushState Oct 25 11:40:21 volumio volumio[793]: info: CoreCommandRouter::servicePushState Oct 25 11:40:21 volumio volumio[793]: info: CoreStateMachine::pushState Oct 25 11:40:21 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Oct 25 11:40:21 volumio volumio[793]: info: MRS: Pushing multiroomSync output update for this device Oct 25 11:40:21 volumio volumio[793]: info: MRS: Pushing multiroomSync output Oct 25 11:40:21 volumio volumio[793]: info: CoreCommandRouter::volumioGetState Oct 25 11:40:21 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Oct 25 11:40:21 volumio volumio[793]: 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} Oct 25 11:40:21 volumio volumio[793]: verbose: CURRENT POSITION 0 Oct 25 11:40:21 volumio volumio[793]: info: CoreStateMachine::syncState stateService stop Oct 25 11:40:21 volumio volumio[793]: info: CoreStateMachine::syncState currentStatus stop Oct 25 11:40:21 volumio volumio[793]: info: CoreStateMachine::pushState Oct 25 11:40:21 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Oct 25 11:40:21 volumio volumio[793]: info: MRS: Pushing multiroomSync output update for this device Oct 25 11:40:21 volumio volumio[793]: info: MRS: Pushing multiroomSync output Oct 25 11:40:21 volumio volumio[793]: info: CoreCommandRouter::volumioGetState Oct 25 11:40:21 volumio volumio[793]: info: No code Oct 25 11:40:21 volumio volumio[793]: info: CoreStateMachine::pushState Oct 25 11:40:21 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Oct 25 11:40:21 volumio volumio[793]: info: MRS: Pushing multiroomSync output update for this device Oct 25 11:40:21 volumio volumio[793]: info: MRS: Pushing multiroomSync output Oct 25 11:40:21 volumio volumio[793]: info: CoreCommandRouter::volumioGetState Oct 25 11:40:21 volumio volumio[793]: info: ControllerMpd::pushState Oct 25 11:40:21 volumio volumio[793]: info: CoreCommandRouter::servicePushState Oct 25 11:40:21 volumio volumio[793]: info: CoreStateMachine::pushState Oct 25 11:40:21 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Oct 25 11:40:21 volumio volumio[793]: info: MRS: Pushing multiroomSync output update for this device Oct 25 11:40:21 volumio volumio[793]: info: MRS: Pushing multiroomSync output Oct 25 11:40:21 volumio volumio[793]: info: CoreCommandRouter::volumioGetState Oct 25 11:40:21 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Oct 25 11:40:21 volumio volumio[793]: 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} Oct 25 11:40:21 volumio volumio[793]: verbose: CURRENT POSITION 0 Oct 25 11:40:21 volumio volumio[793]: info: CoreStateMachine::syncState stateService stop Oct 25 11:40:21 volumio volumio[793]: info: CoreStateMachine::syncState currentStatus stop Oct 25 11:40:21 volumio volumio[793]: info: CoreStateMachine::pushState Oct 25 11:40:21 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Oct 25 11:40:21 volumio volumio[793]: info: MRS: Pushing multiroomSync output update for this device Oct 25 11:40:21 volumio volumio[793]: info: MRS: Pushing multiroomSync output Oct 25 11:40:21 volumio volumio[793]: info: CoreCommandRouter::volumioGetState Oct 25 11:40:21 volumio volumio[793]: info: No code Oct 25 11:40:21 volumio volumio[793]: info: CoreStateMachine::pushState Oct 25 11:40:21 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Oct 25 11:40:21 volumio volumio[793]: info: MRS: Pushing multiroomSync output update for this device Oct 25 11:40:21 volumio volumio[793]: info: MRS: Pushing multiroomSync output Oct 25 11:40:21 volumio volumio[793]: info: CoreCommandRouter::volumioGetState Oct 25 11:40:21 volumio volumio[793]: info: ControllerMpd::pushState Oct 25 11:40:21 volumio volumio[793]: info: CoreCommandRouter::servicePushState Oct 25 11:40:21 volumio volumio[793]: info: CoreStateMachine::pushState Oct 25 11:40:21 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Oct 25 11:40:21 volumio volumio[793]: info: MRS: Pushing multiroomSync output update for this device Oct 25 11:40:21 volumio volumio[793]: info: MRS: Pushing multiroomSync output Oct 25 11:40:21 volumio volumio[793]: info: CoreCommandRouter::volumioGetState Oct 25 11:40:21 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Oct 25 11:40:21 volumio volumio[793]: 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} Oct 25 11:40:21 volumio volumio[793]: verbose: CURRENT POSITION 0 Oct 25 11:40:21 volumio volumio[793]: info: CoreStateMachine::syncState stateService stop Oct 25 11:40:21 volumio volumio[793]: info: CoreStateMachine::syncState currentStatus stop Oct 25 11:40:21 volumio volumio[793]: info: CoreStateMachine::pushState Oct 25 11:40:21 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Oct 25 11:40:21 volumio volumio[793]: info: MRS: Pushing multiroomSync output update for this device Oct 25 11:40:21 volumio volumio[793]: info: MRS: Pushing multiroomSync output Oct 25 11:40:21 volumio volumio[793]: info: CoreCommandRouter::volumioGetState Oct 25 11:40:21 volumio volumio[793]: info: No code Oct 25 11:40:21 volumio volumio[793]: info: CoreStateMachine::pushState Oct 25 11:40:21 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Oct 25 11:40:21 volumio volumio[793]: info: MRS: Pushing multiroomSync output update for this device Oct 25 11:40:21 volumio volumio[793]: info: MRS: Pushing multiroomSync output Oct 25 11:40:21 volumio volumio[793]: info: CoreCommandRouter::volumioGetState Oct 25 11:40:21 volumio volumio[793]: info: ControllerMpd::pushState Oct 25 11:40:21 volumio volumio[793]: info: CoreCommandRouter::servicePushState Oct 25 11:40:21 volumio volumio[793]: info: CoreStateMachine::pushState Oct 25 11:40:21 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Oct 25 11:40:21 volumio volumio[793]: info: MRS: Pushing multiroomSync output update for this device Oct 25 11:40:21 volumio volumio[793]: info: MRS: Pushing multiroomSync output Oct 25 11:40:21 volumio volumio[793]: info: CoreCommandRouter::volumioGetState Oct 25 11:40:21 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Oct 25 11:40:21 volumio volumio[793]: 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} Oct 25 11:40:21 volumio volumio[793]: verbose: CURRENT POSITION 0 Oct 25 11:40:21 volumio volumio[793]: info: CoreStateMachine::syncState stateService stop Oct 25 11:40:21 volumio volumio[793]: info: CoreStateMachine::syncState currentStatus stop Oct 25 11:40:21 volumio volumio[793]: info: CoreStateMachine::pushState Oct 25 11:40:21 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Oct 25 11:40:21 volumio volumio[793]: info: MRS: Pushing multiroomSync output update for this device Oct 25 11:40:21 volumio volumio[793]: info: MRS: Pushing multiroomSync output Oct 25 11:40:21 volumio volumio[793]: info: CoreCommandRouter::volumioGetState Oct 25 11:40:21 volumio volumio[793]: info: No code Oct 25 11:40:21 volumio volumio[793]: info: CoreStateMachine::pushState Oct 25 11:40:21 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Oct 25 11:40:21 volumio volumio[793]: info: MRS: Pushing multiroomSync output update for this device Oct 25 11:40:21 volumio volumio[793]: info: MRS: Pushing multiroomSync output Oct 25 11:40:21 volumio volumio[793]: info: CoreCommandRouter::volumioGetState Oct 25 11:40:21 volumio volumio[793]: info: ------------------------------ 312ms Oct 25 11:40:21 volumio volumio[793]: info: ------------------------------ 309ms Oct 25 11:40:21 volumio volumio[793]: info: ------------------------------ 308ms Oct 25 11:40:21 volumio volumio[793]: info: ------------------------------ 308ms Oct 25 11:40:21 volumio volumio[793]: info: ------------------------------ 307ms Oct 25 11:40:21 volumio volumio[793]: info: ------------------------------ 307ms Oct 25 11:40:21 volumio volumio[793]: info: ------------------------------ 305ms Oct 25 11:40:21 volumio volumio[793]: info: ------------------------------ 305ms Oct 25 11:40:21 volumio volumio[793]: info: ------------------------------ 305ms Oct 25 11:40:21 volumio volumio[793]: info: ------------------------------ 305ms Oct 25 11:40:21 volumio volumio[793]: info: [ASDebug] CurState: PrevState: stop Oct 25 11:40:21 volumio volumio[793]: info: [ASDebug] Togle GPIO: ON Oct 25 11:40:21 volumio volumio[793]: info: [ASDebug] CurState: undefined PrevState: Oct 25 11:40:21 volumio volumio[793]: info: [ASDebug] Togle GPIO: ON Oct 25 11:40:21 volumio volumio[793]: info: [ASDebug] CurState: undefined PrevState: undefined Oct 25 11:40:21 volumio volumio[793]: info: [ASDebug] CurState: undefined PrevState: undefined Oct 25 11:40:21 volumio volumio[793]: info: [ASDebug] CurState: undefined PrevState: undefined Oct 25 11:40:21 volumio volumio[793]: info: [ASDebug] CurState: undefined PrevState: undefined Oct 25 11:40:21 volumio volumio[793]: info: [ASDebug] CurState: undefined PrevState: undefined Oct 25 11:40:21 volumio volumio[793]: info: [ASDebug] CurState: undefined PrevState: undefined Oct 25 11:40:21 volumio volumio[793]: info: [ASDebug] CurState: undefined PrevState: undefined Oct 25 11:40:21 volumio volumio[793]: info: [ASDebug] CurState: undefined PrevState: undefined Oct 25 11:40:21 volumio volumio[793]: info: [ASDebug] CurState: undefined PrevState: undefined Oct 25 11:40:21 volumio volumio[793]: info: [ASDebug] CurState: undefined PrevState: undefined Oct 25 11:40:21 volumio volumio[793]: info: [ASDebug] CurState: undefined PrevState: undefined Oct 25 11:40:21 volumio volumio[793]: info: [ASDebug] CurState: undefined PrevState: undefined Oct 25 11:40:21 volumio volumio[793]: info: [ASDebug] CurState: undefined PrevState: undefined Oct 25 11:40:21 volumio volumio[793]: info: [ASDebug] CurState: undefined PrevState: undefined Oct 25 11:40:21 volumio volumio[793]: info: [ASDebug] CurState: undefined PrevState: undefined Oct 25 11:40:21 volumio volumio[793]: info: [ASDebug] CurState: undefined PrevState: undefined Oct 25 11:40:21 volumio volumio[793]: info: [ASDebug] CurState: undefined PrevState: undefined Oct 25 11:40:21 volumio volumio[793]: info: [ASDebug] CurState: undefined PrevState: undefined Oct 25 11:40:21 volumio volumio[793]: info: [ASDebug] CurState: undefined PrevState: undefined Oct 25 11:40:21 volumio volumio[793]: info: [ASDebug] CurState: undefined PrevState: undefined Oct 25 11:40:21 volumio volumio[793]: info: [ASDebug] CurState: undefined PrevState: undefined Oct 25 11:40:21 volumio volumio[793]: info: [ASDebug] CurState: undefined PrevState: undefined Oct 25 11:40:21 volumio volumio[793]: info: [ASDebug] CurState: undefined PrevState: undefined Oct 25 11:40:21 volumio volumio[793]: info: [ASDebug] CurState: undefined PrevState: undefined Oct 25 11:40:21 volumio volumio[793]: info: [ASDebug] CurState: undefined PrevState: undefined Oct 25 11:40:21 volumio volumio[793]: info: [ASDebug] CurState: undefined PrevState: undefined Oct 25 11:40:21 volumio volumio[793]: info: [ASDebug] CurState: undefined PrevState: undefined Oct 25 11:40:21 volumio volumio[793]: info: [ASDebug] CurState: undefined PrevState: undefined Oct 25 11:40:21 volumio volumio[793]: info: [ASDebug] CurState: undefined PrevState: undefined Oct 25 11:40:21 volumio volumio[793]: info: [ASDebug] CurState: undefined PrevState: undefined Oct 25 11:40:21 volumio volumio[793]: info: [ASDebug] CurState: undefined PrevState: undefined Oct 25 11:40:21 volumio volumio[793]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.g2djgJFE4ROAXuU6F8XosIQqy4z1.7cf13286373bbe3bb2e13a681763011a.state.status' Oct 25 11:40:21 volumio volumio[793]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.g2djgJFE4ROAXuU6F8XosIQqy4z1.7cf13286373bbe3bb2e13a681763011a.state.status' Oct 25 11:40:21 volumio volumio[793]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.g2djgJFE4ROAXuU6F8XosIQqy4z1.7cf13286373bbe3bb2e13a681763011a.state.status' Oct 25 11:40:21 volumio volumio[793]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.g2djgJFE4ROAXuU6F8XosIQqy4z1.7cf13286373bbe3bb2e13a681763011a.state.status' Oct 25 11:40:21 volumio volumio[793]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.g2djgJFE4ROAXuU6F8XosIQqy4z1.7cf13286373bbe3bb2e13a681763011a.state.status' Oct 25 11:40:21 volumio volumio[793]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.g2djgJFE4ROAXuU6F8XosIQqy4z1.7cf13286373bbe3bb2e13a681763011a.state.status' Oct 25 11:40:21 volumio volumio[793]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.g2djgJFE4ROAXuU6F8XosIQqy4z1.7cf13286373bbe3bb2e13a681763011a.state.status' Oct 25 11:40:21 volumio volumio[793]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.g2djgJFE4ROAXuU6F8XosIQqy4z1.7cf13286373bbe3bb2e13a681763011a.state.status' Oct 25 11:40:21 volumio volumio[793]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.g2djgJFE4ROAXuU6F8XosIQqy4z1.7cf13286373bbe3bb2e13a681763011a.state.status' Oct 25 11:40:21 volumio volumio[793]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.g2djgJFE4ROAXuU6F8XosIQqy4z1.7cf13286373bbe3bb2e13a681763011a.state.status' Oct 25 11:40:21 volumio volumio[793]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.g2djgJFE4ROAXuU6F8XosIQqy4z1.7cf13286373bbe3bb2e13a681763011a.state.status' Oct 25 11:40:21 volumio volumio[793]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.g2djgJFE4ROAXuU6F8XosIQqy4z1.7cf13286373bbe3bb2e13a681763011a.state.status' Oct 25 11:40:21 volumio volumio[793]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.g2djgJFE4ROAXuU6F8XosIQqy4z1.7cf13286373bbe3bb2e13a681763011a.state.status' Oct 25 11:40:21 volumio volumio[793]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.g2djgJFE4ROAXuU6F8XosIQqy4z1.7cf13286373bbe3bb2e13a681763011a.state.status' Oct 25 11:40:21 volumio volumio[793]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.g2djgJFE4ROAXuU6F8XosIQqy4z1.7cf13286373bbe3bb2e13a681763011a.state.status' Oct 25 11:40:21 volumio volumio[793]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.g2djgJFE4ROAXuU6F8XosIQqy4z1.7cf13286373bbe3bb2e13a681763011a.state.status' Oct 25 11:40:21 volumio volumio[793]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.g2djgJFE4ROAXuU6F8XosIQqy4z1.7cf13286373bbe3bb2e13a681763011a.state.status' Oct 25 11:40:21 volumio volumio[793]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.g2djgJFE4ROAXuU6F8XosIQqy4z1.7cf13286373bbe3bb2e13a681763011a.state.status' Oct 25 11:40:21 volumio volumio[793]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.g2djgJFE4ROAXuU6F8XosIQqy4z1.7cf13286373bbe3bb2e13a681763011a.state.status' Oct 25 11:40:21 volumio volumio[793]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.g2djgJFE4ROAXuU6F8XosIQqy4z1.7cf13286373bbe3bb2e13a681763011a.state.status' Oct 25 11:40:21 volumio volumio[793]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.g2djgJFE4ROAXuU6F8XosIQqy4z1.7cf13286373bbe3bb2e13a681763011a.state.status' Oct 25 11:40:21 volumio volumio[793]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.g2djgJFE4ROAXuU6F8XosIQqy4z1.7cf13286373bbe3bb2e13a681763011a.state.status' Oct 25 11:40:21 volumio volumio[793]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.g2djgJFE4ROAXuU6F8XosIQqy4z1.7cf13286373bbe3bb2e13a681763011a.state.status' Oct 25 11:40:21 volumio volumio[793]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.g2djgJFE4ROAXuU6F8XosIQqy4z1.7cf13286373bbe3bb2e13a681763011a.state.status' Oct 25 11:40:21 volumio volumio[793]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.g2djgJFE4ROAXuU6F8XosIQqy4z1.7cf13286373bbe3bb2e13a681763011a.state.status' Oct 25 11:40:21 volumio volumio[793]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.g2djgJFE4ROAXuU6F8XosIQqy4z1.7cf13286373bbe3bb2e13a681763011a.state.status' Oct 25 11:40:21 volumio volumio[793]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.g2djgJFE4ROAXuU6F8XosIQqy4z1.7cf13286373bbe3bb2e13a681763011a.state.status' Oct 25 11:40:21 volumio volumio[793]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.g2djgJFE4ROAXuU6F8XosIQqy4z1.7cf13286373bbe3bb2e13a681763011a.state.status' Oct 25 11:40:21 volumio volumio[793]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.g2djgJFE4ROAXuU6F8XosIQqy4z1.7cf13286373bbe3bb2e13a681763011a.state.status' Oct 25 11:40:21 volumio volumio[793]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.g2djgJFE4ROAXuU6F8XosIQqy4z1.7cf13286373bbe3bb2e13a681763011a.state.status' Oct 25 11:40:21 volumio volumio[793]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.g2djgJFE4ROAXuU6F8XosIQqy4z1.7cf13286373bbe3bb2e13a681763011a.state.status' Oct 25 11:40:21 volumio volumio[793]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.g2djgJFE4ROAXuU6F8XosIQqy4z1.7cf13286373bbe3bb2e13a681763011a.state.status' Oct 25 11:40:24 volumio volumio[793]: info: CoreStateMachine::startPlaybackTimer Oct 25 11:40:24 volumio volumio[793]: info: CorePlayQueue::getTrack 1 Oct 25 11:40:24 volumio volumio[793]: info: CoreCommandRouter::volumioGetVisibleSources Oct 25 11:40:24 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 25 11:40:24 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 25 11:40:25 volumio volumio[793]: info: CoreStateMachine::pushState Oct 25 11:40:25 volumio volumio[793]: info: CorePlayQueue::getTrack 1 Oct 25 11:40:25 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 11:40:25 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Oct 25 11:40:25 volumio volumio[793]: info: MRS: Pushing multiroomSync output update for this device Oct 25 11:40:25 volumio volumio[793]: info: MRS: Pushing multiroomSync output Oct 25 11:40:25 volumio volumio[793]: info: CoreCommandRouter::volumioGetState Oct 25 11:40:25 volumio volumio[793]: info: CorePlayQueue::getTrack 1 Oct 25 11:40:25 volumio volumio[793]: info: [ASDebug] CurState: stop PrevState: undefined Oct 25 11:40:25 volumio volumio[793]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Oct 25 11:40:25 volumio volumio[793]: info: [ASDebug] Togle GPIO: OFF Oct 25 11:40:46 volumio volumio[793]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Oct 25 11:40:46 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Oct 25 11:40:46 volumio volumio[793]: info: Preparing to save Alsa Options, stopping services first Oct 25 11:40:46 volumio volumio[793]: info: CoreCommandRouter::volumioGetState Oct 25 11:40:46 volumio volumio[793]: info: CorePlayQueue::getTrack 1 Oct 25 11:40:46 volumio volumio[793]: info: CoreCommandRouter::volumioPause Oct 25 11:40:46 volumio volumio[793]: info: CoreStateMachine::pause Oct 25 11:40:46 volumio volumio[793]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"Gustard USB Audio 2.0"},"i2s":true,"i2sid":{"value":"generic-dac","label":"Generic I2S DAC"}} Oct 25 11:40:46 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 25 11:40:46 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Oct 25 11:40:46 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 11:40:46 volumio volumio[793]: info: Enabling I2S DAC: Generic I2S DAC Oct 25 11:40:46 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , enableI2SDAC Oct 25 11:40:46 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 11:40:46 volumio sudo[8102]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Oct 25 11:40:46 volumio sudo[8102]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 11:40:46 volumio sudo[8102]: pam_unix(sudo:session): session closed for user root Oct 25 11:40:46 volumio volumio[793]: info: No Overlays Loaded Oct 25 11:40:46 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 11:40:46 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName Oct 25 11:40:46 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 11:40:46 volumio sudo[8105]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay hifiberry-dac Oct 25 11:40:46 volumio sudo[8105]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 11:40:46 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2s@7e203000/status Oct 25 11:40:46 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/compatible Oct 25 11:40:46 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/i2s-controller Oct 25 11:40:46 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/status Oct 25 11:40:46 volumio sudo[8105]: pam_unix(sudo:session): session closed for user root Oct 25 11:40:46 volumio volumio[793]: xcb_connection_has_error() returned true Oct 25 11:40:46 volumio volumio[793]: warn: Unable to locate the device Gustard USB Audio 2.0 a reboot may be required. Oct 25 11:40:46 volumio volumio[793]: xcb_connection_has_error() returned true Oct 25 11:40:46 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 25 11:40:46 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Oct 25 11:40:46 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 11:40:46 volumio volumio[793]: xcb_connection_has_error() returned true Oct 25 11:40:46 volumio volumio[793]: info: Setting default mixerSoftMaster for Softvolume device Oct 25 11:40:46 volumio volumio[793]: info: QobuzConnect: setDeactiveState invoked Oct 25 11:40:46 volumio volumio[793]: info: CoreCommandRouter::volumioGetState Oct 25 11:40:46 volumio volumio[793]: info: CorePlayQueue::getTrack 1 Oct 25 11:40:46 volumio vtcs[7685]: [2025-10-25 11:40:46.879] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Oct 25 11:40:46 volumio vtcs[7685]: [2025-10-25 11:40:46.879] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Oct 25 11:40:46 volumio sudo[8128]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 25 11:40:46 volumio sudo[8128]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 11:40:46 volumio systemd[1]: Stopping Volumio Tidal Connect Service... Oct 25 11:40:46 volumio systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Oct 25 11:40:46 volumio systemd[1]: vtcs.service: Succeeded. Oct 25 11:40:46 volumio systemd[1]: Stopped Volumio Tidal Connect Service. Oct 25 11:40:46 volumio volumio[793]: xcb_connection_has_error() returned true Oct 25 11:40:46 volumio volumio[793]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 25 11:40:46 volumio volumio[793]: info: Updating Volume Controller Parameters: Device: 2 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 5 Oct 25 11:40:46 volumio sudo[8128]: pam_unix(sudo:session): session closed for user root Oct 25 11:40:46 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 25 11:40:46 volumio volumio[793]: info: Disabling external Volume Control Oct 25 11:40:46 volumio volumio[793]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 25 11:40:46 volumio volumio[793]: info: Enable softmixer device for audio device undefined Oct 25 11:40:46 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Oct 25 11:40:47 volumio sudo[8141]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 25 11:40:47 volumio sudo[8141]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 11:40:47 volumio sudo[8141]: pam_unix(sudo:session): session closed for user root Oct 25 11:40:47 volumio sudo[8147]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 25 11:40:47 volumio sudo[8147]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 11:40:47 volumio volumio[793]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 25 11:40:47 volumio sudo[8147]: pam_unix(sudo:session): session closed for user root Oct 25 11:40:47 volumio volumio[793]: info: CoreStateMachine::pushState Oct 25 11:40:47 volumio volumio[793]: info: CorePlayQueue::getTrack 1 Oct 25 11:40:47 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 11:40:47 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Oct 25 11:40:47 volumio volumio[793]: info: MRS: Pushing multiroomSync output update for this device Oct 25 11:40:47 volumio volumio[793]: info: MRS: Pushing multiroomSync output Oct 25 11:40:47 volumio volumio[793]: info: CoreCommandRouter::volumioGetState Oct 25 11:40:47 volumio volumio[793]: info: CorePlayQueue::getTrack 1 Oct 25 11:40:47 volumio sudo[8150]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 25 11:40:47 volumio sudo[8150]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 11:40:47 volumio volumio[793]: info: I2S Param [object Object] successfully enabled Oct 25 11:40:47 volumio volumio[793]: info: [ASDebug] CurState: stop PrevState: stop Oct 25 11:40:47 volumio qobuz-connect[7659]: 20251025 11:40:47.106 [7659.7659] INFO SampleApp: Stopping Local configuration server Oct 25 11:40:47 volumio systemd[1]: Stopping Volumio Qobuz Connect Service... Oct 25 11:40:47 volumio volumio[793]: info: Software Volume ALSA configuration written Oct 25 11:40:47 volumio volumio[793]: info: Preparing to generate the ALSA configuration file Oct 25 11:40:47 volumio qobuz-connect[7659]: 20251025 11:40:47.116 [7659.7659] INFO SampleApp: shat down connection on UNIX socket Oct 25 11:40:47 volumio systemd[1]: qobuz-connect.service: Succeeded. Oct 25 11:40:47 volumio systemd[1]: Stopped Volumio Qobuz Connect Service. Oct 25 11:40:47 volumio volumio[793]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 25 11:40:47 volumio volumio[793]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 25 11:40:47 volumio volumio[793]: xcb_connection_has_error() returned true Oct 25 11:40:47 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 25 11:40:47 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 11:40:47 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 25 11:40:47 volumio volumio[793]: info: Getting Alsa Cards List without I2S DAC Oct 25 11:40:47 volumio systemd[1]: Started Volumio Qobuz Connect Service. Oct 25 11:40:47 volumio sudo[8150]: pam_unix(sudo:session): session closed for user root Oct 25 11:40:47 volumio volumio[793]: xcb_connection_has_error() returned true Oct 25 11:40:47 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Oct 25 11:40:47 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 11:40:47 volumio volumio[793]: xcb_connection_has_error() returned true Oct 25 11:40:47 volumio volumio[793]: xcb_connection_has_error() returned true Oct 25 11:40:47 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 25 11:40:47 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 25 11:40:47 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 25 11:40:47 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 25 11:40:47 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 25 11:40:47 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 25 11:40:47 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 25 11:40:47 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 25 11:40:47 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 25 11:40:47 volumio volumio[793]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Oct 25 11:40:47 volumio volumio[793]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 25 11:40:47 volumio volumio[793]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 25 11:40:47 volumio volumio[793]: info: Reading ALSA contributions from plugins. Oct 25 11:40:47 volumio volumio[793]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Oct 25 11:40:47 volumio volumio[793]: info: QobuzConnect: setDeactiveState invoked Oct 25 11:40:47 volumio volumio[793]: info: CoreCommandRouter::volumioGetState Oct 25 11:40:47 volumio volumio[793]: info: CorePlayQueue::getTrack 1 Oct 25 11:40:47 volumio volumio[793]: info: Executing endpoint qc_getconfig Oct 25 11:40:47 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 25 11:40:47 volumio qobuz-connect[8152]: 20251025 11:40:47.345 [8152.8152] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 25 11:40:47 volumio volumio[793]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf. Oct 25 11:40:47 volumio volumio[793]: info: CoreCommandRouter::volumioGetState Oct 25 11:40:47 volumio volumio[793]: info: CorePlayQueue::getTrack 1 Oct 25 11:40:47 volumio volumio[793]: info: CoreCommandRouter::volumioPause Oct 25 11:40:47 volumio volumio[793]: info: CoreStateMachine::pause Oct 25 11:40:47 volumio volumio[793]: info: mpdhttpout --- Volumio set on pause Oct 25 11:40:47 volumio volumio[793]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Oct 25 11:40:47 volumio volumio[793]: info: QobuzConnect: QOBUZ Connect daemon connected Oct 25 11:40:47 volumio qobuz-connect[8152]: 20251025 11:40:47.349 [8152.8152] INFO VolumeManager: [0x19a6a38]: Setting new playback volume: 75 Oct 25 11:40:47 volumio qobuz-connect[8152]: 20251025 11:40:47.349 [8152.8152] INFO VolumeManager: [0x19a6a38]: Setting new mute state: 0 Oct 25 11:40:47 volumio qobuz-connect[8152]: 20251025 11:40:47.349 [8152.8152] INFO QobuzConnect: [0x19a7408]: Client initialized! Oct 25 11:40:47 volumio qobuz-connect[8152]: 20251025 11:40:47.349 [8152.8152] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Oct 25 11:40:47 volumio volumio[793]: info: Asound.conf file written Oct 25 11:40:47 volumio qobuz-connect[8152]: 20251025 11:40:47.355 [8152.8152] INFO LocalConfigManager: [0x19a6318]: Starting Local Configuration server Oct 25 11:40:47 volumio qobuz-connect[8152]: 20251025 11:40:47.355 [8152.8152] INFO SampleApp: Starting Local configuration server Oct 25 11:40:47 volumio qobuz-connect[8152]: 20251025 11:40:47.356 [8152.8152] INFO SampleApp: Connected to UNIX socket client 0x199bf60 Oct 25 11:40:47 volumio sudo[8164]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 25 11:40:47 volumio sudo[8164]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 11:40:47 volumio sudo[8164]: pam_unix(sudo:session): session closed for user root Oct 25 11:40:47 volumio volumio[793]: xcb_connection_has_error() returned true Oct 25 11:40:47 volumio volumio[793]: No state is present for card G20 Oct 25 11:40:47 volumio volumio[793]: Found hardware: "USB-Audio" "USB Mixer" "USB20b1:3033" "" "" Oct 25 11:40:47 volumio volumio[793]: Hardware is initialized using a generic method Oct 25 11:40:47 volumio volumio[793]: No state is present for card G20 Oct 25 11:40:47 volumio volumio[793]: info: Output device has changed, restarting MPD Oct 25 11:40:47 volumio sudo[8169]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 25 11:40:47 volumio sudo[8169]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 11:40:47 volumio volumio[793]: info: Output device has changed, restarting Shairport Sync Oct 25 11:40:47 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 11:40:47 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 11:40:47 volumio sudo[8169]: pam_unix(sudo:session): session closed for user root Oct 25 11:40:47 volumio sudo[8172]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 25 11:40:47 volumio sudo[8172]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 11:40:47 volumio systemd[1]: musicservicesshield.service: Succeeded. Oct 25 11:40:47 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Oct 25 11:40:47 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Oct 25 11:40:47 volumio systemd[1]: Stopping Music Player Daemon... Oct 25 11:40:47 volumio qobuz-connect[8152]: 20251025 11:40:47.495 [8152.8152] INFO SampleApp: Playback volume changed: 75 Oct 25 11:40:47 volumio volumio[793]: xcb_connection_has_error() returned true Oct 25 11:40:47 volumio volumio[793]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 25 11:40:47 volumio volumio[793]: xcb_connection_has_error() returned true Oct 25 11:40:47 volumio volumio[793]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 25 11:40:47 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 11:40:47 volumio systemd[1]: mpd.service: Succeeded. Oct 25 11:40:47 volumio systemd[1]: Stopped Music Player Daemon. Oct 25 11:40:47 volumio volumio[793]: info: QobuzConnect: setDeactiveState invoked Oct 25 11:40:47 volumio volumio[793]: info: CoreCommandRouter::volumioGetState Oct 25 11:40:47 volumio volumio[793]: info: CorePlayQueue::getTrack 1 Oct 25 11:40:47 volumio volumio[793]: info: Output device has changed, restarting MPD Oct 25 11:40:47 volumio volumio[793]: info: Output device has changed, restarting Shairport Sync Oct 25 11:40:47 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 11:40:47 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 11:40:47 volumio sudo[8181]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 25 11:40:47 volumio sudo[8181]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 11:40:47 volumio systemd[1]: Starting Music Player Daemon... Oct 25 11:40:47 volumio sudo[8184]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 25 11:40:47 volumio sudo[8184]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 11:40:47 volumio sudo[8184]: pam_unix(sudo:session): session closed for user root Oct 25 11:40:47 volumio sudo[8181]: pam_unix(sudo:session): session closed for user root Oct 25 11:40:47 volumio sudo[8186]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 25 11:40:47 volumio sudo[8186]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 11:40:47 volumio volumio[793]: xcb_connection_has_error() returned true Oct 25 11:40:47 volumio volumio[793]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 25 11:40:47 volumio volumio[793]: xcb_connection_has_error() returned true Oct 25 11:40:47 volumio volumio[793]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 25 11:40:47 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 11:40:47 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Oct 25 11:40:47 volumio systemd[1]: mpd.service: Succeeded. Oct 25 11:40:47 volumio systemd[1]: Stopped Music Player Daemon. Oct 25 11:40:47 volumio systemd[1]: Starting Music Player Daemon... Oct 25 11:40:47 volumio volumio[793]: info: QobuzConnect: setDeactiveState invoked Oct 25 11:40:47 volumio volumio[793]: info: CoreCommandRouter::volumioGetState Oct 25 11:40:47 volumio volumio[793]: info: CorePlayQueue::getTrack 1 Oct 25 11:40:47 volumio sudo[8197]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 25 11:40:47 volumio sudo[8197]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 11:40:47 volumio sudo[8197]: pam_unix(sudo:session): session closed for user root Oct 25 11:40:47 volumio sudo[8194]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 25 11:40:47 volumio sudo[8194]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 11:40:47 volumio sudo[8194]: pam_unix(sudo:session): session closed for user root Oct 25 11:40:47 volumio sudo[8202]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 25 11:40:47 volumio sudo[8202]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 11:40:47 volumio sudo[8200]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 25 11:40:47 volumio sudo[8200]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 11:40:47 volumio sudo[8200]: pam_unix(sudo:session): session closed for user root Oct 25 11:40:47 volumio sudo[8202]: pam_unix(sudo:session): session closed for user root Oct 25 11:40:47 volumio sudo[8214]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 25 11:40:47 volumio sudo[8214]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 11:40:47 volumio sudo[8214]: pam_unix(sudo:session): session closed for user root Oct 25 11:40:47 volumio sudo[8221]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 25 11:40:47 volumio volumio[793]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 25 11:40:47 volumio volumio[793]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 25 11:40:47 volumio volumio[793]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 25 11:40:47 volumio sudo[8220]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 25 11:40:47 volumio sudo[8220]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 11:40:47 volumio sudo[8221]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 11:40:47 volumio volumio[793]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 25 11:40:47 volumio volumio[793]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 25 11:40:47 volumio sudo[8221]: pam_unix(sudo:session): session closed for user root Oct 25 11:40:47 volumio volumio[793]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 25 11:40:47 volumio volumio[793]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 25 11:40:47 volumio volumio[793]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 25 11:40:47 volumio systemd[1]: Stopping Volumio Qobuz Connect Service... Oct 25 11:40:47 volumio qobuz-connect[8152]: 20251025 11:40:47.928 [8152.8152] INFO SampleApp: Stopping Local configuration server Oct 25 11:40:47 volumio qobuz-connect[8152]: 20251025 11:40:47.939 [8152.8152] INFO SampleApp: shat down connection on UNIX socket Oct 25 11:40:47 volumio systemd[1]: qobuz-connect.service: Succeeded. Oct 25 11:40:47 volumio systemd[1]: Stopped Volumio Qobuz Connect Service. Oct 25 11:40:47 volumio volumio[793]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 25 11:40:47 volumio volumio[793]: info: CoreCommandRouter::volumioGetState Oct 25 11:40:47 volumio volumio[793]: info: CorePlayQueue::getTrack 1 Oct 25 11:40:47 volumio sudo[8224]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 25 11:40:47 volumio sudo[8224]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 11:40:48 volumio volumio[793]: info: MPD Permissions set Oct 25 11:40:48 volumio volumio[793]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 25 11:40:48 volumio volumio[793]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 25 11:40:48 volumio volumio[793]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Oct 25 11:40:48 volumio volumio[793]: info: QobuzConnect: setDeactiveState invoked Oct 25 11:40:48 volumio volumio[793]: info: CoreCommandRouter::volumioGetState Oct 25 11:40:48 volumio volumio[793]: info: CorePlayQueue::getTrack 1 Oct 25 11:40:48 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 11:40:48 volumio systemd[1]: Started Volumio Qobuz Connect Service. Oct 25 11:40:48 volumio sudo[8220]: pam_unix(sudo:session): session closed for user root Oct 25 11:40:48 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 11:40:48 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 11:40:48 volumio systemd[1]: Stopping Volumio Qobuz Connect Service... Oct 25 11:40:48 volumio systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=2/INT Oct 25 11:40:48 volumio systemd[1]: qobuz-connect.service: Succeeded. Oct 25 11:40:48 volumio systemd[1]: Stopped Volumio Qobuz Connect Service. Oct 25 11:40:48 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 11:40:48 volumio systemd[1]: Started Volumio Qobuz Connect Service. Oct 25 11:40:48 volumio sudo[8224]: pam_unix(sudo:session): session closed for user root Oct 25 11:40:48 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 11:40:48 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 11:40:48 volumio volumio[793]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 25 11:40:48 volumio volumio[793]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 25 11:40:48 volumio volumio[793]: info: Starting Shairport Sync Oct 25 11:40:48 volumio volumio[793]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf. Oct 25 11:40:48 volumio volumio[793]: info: CoreCommandRouter::volumioGetState Oct 25 11:40:48 volumio volumio[793]: info: CorePlayQueue::getTrack 1 Oct 25 11:40:48 volumio volumio[793]: info: CoreCommandRouter::volumioPause Oct 25 11:40:48 volumio volumio[793]: info: CoreStateMachine::pause Oct 25 11:40:48 volumio volumio[793]: info: mpdhttpout --- Volumio set on pause Oct 25 11:40:48 volumio volumio[793]: info: Starting Shairport Sync Oct 25 11:40:48 volumio sudo[8238]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 25 11:40:48 volumio sudo[8238]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 11:40:48 volumio volumio[793]: info: Executing endpoint qc_getconfig Oct 25 11:40:48 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 25 11:40:48 volumio volumio[793]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf. Oct 25 11:40:48 volumio volumio[793]: info: CoreCommandRouter::volumioGetState Oct 25 11:40:48 volumio volumio[793]: info: CorePlayQueue::getTrack 1 Oct 25 11:40:48 volumio volumio[793]: info: CoreCommandRouter::volumioPause Oct 25 11:40:48 volumio volumio[793]: info: CoreStateMachine::pause Oct 25 11:40:48 volumio volumio[793]: info: mpdhttpout --- Volumio set on pause Oct 25 11:40:48 volumio volumio[793]: info: Preparing to generate the ALSA configuration file Oct 25 11:40:48 volumio qobuz-connect[8231]: 20251025 11:40:48.159 [8231.8231] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 25 11:40:48 volumio qobuz-connect[8231]: 20251025 11:40:48.163 [8231.8231] INFO VolumeManager: [0xc0aa38]: Setting new playback volume: 75 Oct 25 11:40:48 volumio qobuz-connect[8231]: 20251025 11:40:48.163 [8231.8231] INFO VolumeManager: [0xc0aa38]: Setting new mute state: 0 Oct 25 11:40:48 volumio qobuz-connect[8231]: 20251025 11:40:48.163 [8231.8231] INFO QobuzConnect: [0xc0b408]: Client initialized! Oct 25 11:40:48 volumio qobuz-connect[8231]: 20251025 11:40:48.163 [8231.8231] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Oct 25 11:40:48 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 25 11:40:48 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 25 11:40:48 volumio qobuz-connect[8231]: 20251025 11:40:48.180 [8231.8231] INFO LocalConfigManager: [0xc0a318]: Starting Local Configuration server Oct 25 11:40:48 volumio qobuz-connect[8231]: 20251025 11:40:48.181 [8231.8231] INFO SampleApp: Starting Local configuration server Oct 25 11:40:48 volumio qobuz-connect[8231]: 20251025 11:40:48.182 [8231.8231] INFO SampleApp: Connected to UNIX socket client 0xbfff60 Oct 25 11:40:48 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 25 11:40:48 volumio volumio[793]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Oct 25 11:40:48 volumio volumio[793]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 25 11:40:48 volumio volumio[793]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 25 11:40:48 volumio volumio[793]: info: Reading ALSA contributions from plugins. Oct 25 11:40:48 volumio volumio[793]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Oct 25 11:40:48 volumio volumio[793]: info: QobuzConnect: QOBUZ Connect daemon connected Oct 25 11:40:48 volumio sudo[8240]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 25 11:40:48 volumio sudo[8240]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 11:40:48 volumio volumio[793]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 25 11:40:48 volumio volumio[793]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 25 11:40:48 volumio volumio[793]: info: Asound.conf file unchanged, so no further update is needed Oct 25 11:40:48 volumio volumio[793]: info: Output device has changed, restarting MPD Oct 25 11:40:48 volumio volumio[793]: info: Output device has changed, restarting Shairport Sync Oct 25 11:40:48 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 11:40:48 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 11:40:48 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 25 11:40:48 volumio sudo[8246]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 25 11:40:48 volumio sudo[8246]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 11:40:48 volumio sudo[8246]: pam_unix(sudo:session): session closed for user root Oct 25 11:40:48 volumio sudo[8238]: pam_unix(sudo:session): session closed for user root Oct 25 11:40:48 volumio sudo[8249]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 25 11:40:48 volumio sudo[8249]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 11:40:48 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 25 11:40:48 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Oct 25 11:40:48 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 25 11:40:48 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 25 11:40:48 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 25 11:40:48 volumio qobuz-connect[8231]: 20251025 11:40:48.308 [8231.8231] INFO SampleApp: Playback volume changed: 75 Oct 25 11:40:48 volumio sudo[8240]: pam_unix(sudo:session): session closed for user root Oct 25 11:40:48 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 25 11:40:48 volumio systemd[1]: mpd.service: Succeeded. Oct 25 11:40:48 volumio systemd[1]: Stopped Music Player Daemon. Oct 25 11:40:48 volumio systemd[1]: Starting Music Player Daemon... Oct 25 11:40:48 volumio volumio[793]: xcb_connection_has_error() returned true Oct 25 11:40:48 volumio volumio[793]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 25 11:40:48 volumio volumio[793]: xcb_connection_has_error() returned true Oct 25 11:40:48 volumio volumio[793]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 25 11:40:48 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 11:40:48 volumio volumio[793]: info: QobuzConnect: setDeactiveState invoked Oct 25 11:40:48 volumio volumio[793]: info: CoreCommandRouter::volumioGetState Oct 25 11:40:48 volumio volumio[793]: info: CorePlayQueue::getTrack 1 Oct 25 11:40:48 volumio volumio[793]: info: Preparing to generate the ALSA configuration file Oct 25 11:40:48 volumio sudo[8255]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 25 11:40:48 volumio sudo[8255]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 11:40:48 volumio sudo[8255]: pam_unix(sudo:session): session closed for user root Oct 25 11:40:48 volumio sudo[8261]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 25 11:40:48 volumio sudo[8261]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 11:40:48 volumio sudo[8261]: pam_unix(sudo:session): session closed for user root Oct 25 11:40:48 volumio sudo[8267]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 25 11:40:48 volumio sudo[8267]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 11:40:48 volumio sudo[8267]: pam_unix(sudo:session): session closed for user root Oct 25 11:40:48 volumio volumio[793]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Oct 25 11:40:48 volumio volumio[793]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 25 11:40:48 volumio volumio[793]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 25 11:40:48 volumio volumio[793]: info: Reading ALSA contributions from plugins. Oct 25 11:40:48 volumio volumio[793]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 25 11:40:48 volumio volumio[793]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 25 11:40:48 volumio volumio[793]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 25 11:40:48 volumio volumio[793]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 25 11:40:48 volumio volumio[793]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 25 11:40:48 volumio volumio[793]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 25 11:40:48 volumio sudo[8273]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 25 11:40:48 volumio sudo[8273]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 11:40:48 volumio volumio[793]: info: CoreCommandRouter::volumioGetState Oct 25 11:40:48 volumio volumio[793]: info: CorePlayQueue::getTrack 1 Oct 25 11:40:48 volumio volumio[793]: info: MPD Permissions set Oct 25 11:40:48 volumio volumio[793]: info: Shairport-Sync Started Oct 25 11:40:48 volumio sudo[8273]: pam_unix(sudo:session): session closed for user root Oct 25 11:40:48 volumio volumio[793]: (node:793) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pbeg listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Oct 25 11:40:48 volumio volumio[793]: (Use `node --trace-warnings ...` to show where the warning was created) Oct 25 11:40:48 volumio volumio[793]: (node:793) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 meta listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Oct 25 11:40:48 volumio volumio[793]: (node:793) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 prgr listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Oct 25 11:40:48 volumio volumio[793]: (node:793) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pvol listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Oct 25 11:40:48 volumio volumio[793]: (node:793) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pend listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Oct 25 11:40:48 volumio volumio[793]: info: Shairport-Sync Started Oct 25 11:40:48 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 11:40:48 volumio sudo[8276]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 25 11:40:48 volumio sudo[8276]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 11:40:48 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 11:40:48 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 11:40:48 volumio volumio[793]: info: Starting Shairport Sync Oct 25 11:40:48 volumio systemd[1]: Stopping Volumio Qobuz Connect Service... Oct 25 11:40:48 volumio qobuz-connect[8231]: 20251025 11:40:48.633 [8231.8231] INFO SampleApp: Stopping Local configuration server Oct 25 11:40:48 volumio volumio[793]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf. Oct 25 11:40:48 volumio volumio[793]: info: CoreCommandRouter::volumioGetState Oct 25 11:40:48 volumio volumio[793]: info: CorePlayQueue::getTrack 1 Oct 25 11:40:48 volumio volumio[793]: info: CoreCommandRouter::volumioPause Oct 25 11:40:48 volumio volumio[793]: info: CoreStateMachine::pause Oct 25 11:40:48 volumio qobuz-connect[8231]: 20251025 11:40:48.648 [8231.8231] INFO SampleApp: shat down connection on UNIX socket Oct 25 11:40:48 volumio systemd[1]: qobuz-connect.service: Succeeded. Oct 25 11:40:48 volumio systemd[1]: Stopped Volumio Qobuz Connect Service. Oct 25 11:40:48 volumio sudo[8283]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 25 11:40:48 volumio sudo[8283]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 11:40:48 volumio volumio[793]: info: mpdhttpout --- Volumio set on pause Oct 25 11:40:48 volumio volumio[793]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 25 11:40:48 volumio volumio[793]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 25 11:40:48 volumio volumio[793]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Oct 25 11:40:48 volumio volumio[793]: info: QobuzConnect: setDeactiveState invoked Oct 25 11:40:48 volumio volumio[793]: info: CoreCommandRouter::volumioGetState Oct 25 11:40:48 volumio volumio[793]: info: CorePlayQueue::getTrack 1 Oct 25 11:40:48 volumio volumio[793]: info: Asound.conf file unchanged, so no further update is needed Oct 25 11:40:48 volumio volumio[793]: info: Output device has changed, restarting MPD Oct 25 11:40:48 volumio systemd[1]: Started Volumio Qobuz Connect Service. Oct 25 11:40:48 volumio sudo[8276]: pam_unix(sudo:session): session closed for user root Oct 25 11:40:48 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 25 11:40:48 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 25 11:40:48 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 25 11:40:48 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 25 11:40:48 volumio sudo[8283]: pam_unix(sudo:session): session closed for user root Oct 25 11:40:48 volumio sudo[8287]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 25 11:40:48 volumio sudo[8287]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 11:40:48 volumio sudo[8287]: pam_unix(sudo:session): session closed for user root Oct 25 11:40:48 volumio volumio[793]: info: Output device has changed, restarting Shairport Sync Oct 25 11:40:48 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 11:40:48 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 11:40:48 volumio sudo[8292]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 25 11:40:48 volumio sudo[8292]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 11:40:48 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 25 11:40:48 volumio systemd[1]: mpd.service: Succeeded. Oct 25 11:40:48 volumio systemd[1]: Stopped Music Player Daemon. Oct 25 11:40:48 volumio volumio[793]: xcb_connection_has_error() returned true Oct 25 11:40:48 volumio volumio[793]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 25 11:40:48 volumio volumio[793]: xcb_connection_has_error() returned true Oct 25 11:40:48 volumio volumio[793]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 25 11:40:48 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 11:40:48 volumio volumio[793]: info: QobuzConnect: setDeactiveState invoked Oct 25 11:40:48 volumio volumio[793]: info: CoreCommandRouter::volumioGetState Oct 25 11:40:48 volumio volumio[793]: info: CorePlayQueue::getTrack 1 Oct 25 11:40:48 volumio systemd[1]: Starting Music Player Daemon... Oct 25 11:40:48 volumio sudo[8303]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 25 11:40:48 volumio sudo[8303]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 11:40:48 volumio sudo[8306]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 25 11:40:48 volumio sudo[8303]: pam_unix(sudo:session): session closed for user root Oct 25 11:40:48 volumio sudo[8306]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 11:40:48 volumio sudo[8299]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 25 11:40:48 volumio sudo[8299]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 11:40:48 volumio sudo[8299]: pam_unix(sudo:session): session closed for user root Oct 25 11:40:48 volumio sudo[8314]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 25 11:40:48 volumio sudo[8314]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 11:40:48 volumio volumio[793]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 25 11:40:48 volumio volumio[793]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 25 11:40:48 volumio volumio[793]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 25 11:40:48 volumio volumio[793]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 25 11:40:48 volumio volumio[793]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 25 11:40:49 volumio sudo[8306]: pam_unix(sudo:session): session closed for user root Oct 25 11:40:49 volumio sudo[8314]: pam_unix(sudo:session): session closed for user root Oct 25 11:40:49 volumio volumio[793]: info: MPD Permissions set Oct 25 11:40:49 volumio volumio[793]: info: Shairport-Sync Started Oct 25 11:40:49 volumio volumio[793]: info: Executing endpoint qc_getconfig Oct 25 11:40:49 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 25 11:40:49 volumio qobuz-connect[8285]: 20251025 11:40:49.027 [8285.8285] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 25 11:40:49 volumio qobuz-connect[8285]: 20251025 11:40:49.031 [8285.8285] INFO VolumeManager: [0x1f66a38]: Setting new playback volume: 75 Oct 25 11:40:49 volumio qobuz-connect[8285]: 20251025 11:40:49.031 [8285.8285] INFO VolumeManager: [0x1f66a38]: Setting new mute state: 0 Oct 25 11:40:49 volumio qobuz-connect[8285]: 20251025 11:40:49.031 [8285.8285] INFO QobuzConnect: [0x1f67408]: Client initialized! Oct 25 11:40:49 volumio qobuz-connect[8285]: 20251025 11:40:49.031 [8285.8285] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Oct 25 11:40:49 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 11:40:49 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 11:40:49 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 11:40:49 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 11:40:49 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 11:40:49 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 11:40:49 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 11:40:49 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 11:40:49 volumio sudo[8318]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 25 11:40:49 volumio sudo[8318]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 11:40:49 volumio qobuz-connect[8285]: 20251025 11:40:49.049 [8285.8285] INFO LocalConfigManager: [0x1f66318]: Starting Local Configuration server Oct 25 11:40:49 volumio qobuz-connect[8285]: 20251025 11:40:49.049 [8285.8285] INFO SampleApp: Starting Local configuration server Oct 25 11:40:49 volumio qobuz-connect[8285]: 20251025 11:40:49.049 [8285.8285] INFO SampleApp: Connected to UNIX socket client 0x1f5bf60 Oct 25 11:40:49 volumio systemd[1]: Stopping Volumio Qobuz Connect Service... Oct 25 11:40:49 volumio qobuz-connect[8285]: 20251025 11:40:49.066 [8285.8285] INFO SampleApp: Stopping Local configuration server Oct 25 11:40:49 volumio qobuz-connect[8285]: 20251025 11:40:49.076 [8285.8285] INFO SampleApp: shat down connection on UNIX socket Oct 25 11:40:49 volumio systemd[1]: qobuz-connect.service: Succeeded. Oct 25 11:40:49 volumio systemd[1]: Stopped Volumio Qobuz Connect Service. Oct 25 11:40:49 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 11:40:49 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 25 11:40:49 volumio volumio[793]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Oct 25 11:40:49 volumio volumio[793]: info: QobuzConnect: QOBUZ Connect daemon connected Oct 25 11:40:49 volumio volumio[793]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 25 11:40:49 volumio volumio[793]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 25 11:40:49 volumio volumio[793]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Oct 25 11:40:49 volumio volumio[793]: info: QobuzConnect: setDeactiveState invoked Oct 25 11:40:49 volumio volumio[793]: info: CoreCommandRouter::volumioGetState Oct 25 11:40:49 volumio volumio[793]: info: CorePlayQueue::getTrack 1 Oct 25 11:40:49 volumio volumio[793]: info: Starting Shairport Sync Oct 25 11:40:49 volumio volumio[793]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf. Oct 25 11:40:49 volumio volumio[793]: info: CoreCommandRouter::volumioGetState Oct 25 11:40:49 volumio volumio[793]: info: CorePlayQueue::getTrack 1 Oct 25 11:40:49 volumio volumio[793]: info: CoreCommandRouter::volumioPause Oct 25 11:40:49 volumio volumio[793]: info: CoreStateMachine::pause Oct 25 11:40:49 volumio volumio[793]: info: mpdhttpout --- Volumio set on pause Oct 25 11:40:49 volumio sudo[8327]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 25 11:40:49 volumio sudo[8327]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 11:40:49 volumio systemd[1]: Started Volumio Qobuz Connect Service. Oct 25 11:40:49 volumio sudo[8318]: pam_unix(sudo:session): session closed for user root Oct 25 11:40:49 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 25 11:40:49 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 25 11:40:49 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 25 11:40:49 volumio volumio[793]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 25 11:40:49 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 25 11:40:49 volumio volumio[793]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 25 11:40:49 volumio volumio[793]: info: Executing endpoint qc_getconfig Oct 25 11:40:49 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 25 11:40:49 volumio sudo[8327]: pam_unix(sudo:session): session closed for user root Oct 25 11:40:49 volumio qobuz-connect[8325]: 20251025 11:40:49.211 [8325.8325] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 25 11:40:49 volumio volumio[793]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Oct 25 11:40:49 volumio volumio[793]: info: QobuzConnect: QOBUZ Connect daemon connected Oct 25 11:40:49 volumio volumio[793]: info: Shairport-Sync Started Oct 25 11:40:49 volumio qobuz-connect[8325]: 20251025 11:40:49.222 [8325.8325] INFO VolumeManager: [0x2348a38]: Setting new playback volume: 75 Oct 25 11:40:49 volumio qobuz-connect[8325]: 20251025 11:40:49.222 [8325.8325] INFO VolumeManager: [0x2348a38]: Setting new mute state: 0 Oct 25 11:40:49 volumio qobuz-connect[8325]: 20251025 11:40:49.222 [8325.8325] INFO QobuzConnect: [0x2349408]: Client initialized! Oct 25 11:40:49 volumio qobuz-connect[8325]: 20251025 11:40:49.223 [8325.8325] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Oct 25 11:40:49 volumio qobuz-connect[8325]: 20251025 11:40:49.237 [8325.8325] INFO LocalConfigManager: [0x2348318]: Starting Local Configuration server Oct 25 11:40:49 volumio qobuz-connect[8325]: 20251025 11:40:49.238 [8325.8325] INFO SampleApp: Starting Local configuration server Oct 25 11:40:49 volumio qobuz-connect[8325]: 20251025 11:40:49.238 [8325.8325] INFO SampleApp: Connected to UNIX socket client 0x233df60 Oct 25 11:40:49 volumio qobuz-connect[8325]: 20251025 11:40:49.361 [8325.8325] INFO SampleApp: Playback volume changed: 75 Oct 25 11:40:49 volumio volumio[793]: info: CoreCommandRouter::volumioGetState Oct 25 11:40:49 volumio volumio[793]: info: CorePlayQueue::getTrack 1 Oct 25 11:40:49 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Oct 25 11:40:49 volumio sudo[8335]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 25 11:40:49 volumio sudo[8335]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 11:40:49 volumio volumio[793]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 25 11:40:49 volumio volumio[793]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 25 11:40:49 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 25 11:40:49 volumio systemd[1]: mpd.service: Succeeded. Oct 25 11:40:49 volumio systemd[1]: Stopped Music Player Daemon. Oct 25 11:40:49 volumio volumio[793]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 25 11:40:49 volumio systemd[1]: Starting Music Player Daemon... Oct 25 11:40:49 volumio volumio[793]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 25 11:40:49 volumio sudo[8337]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 25 11:40:49 volumio sudo[8337]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 11:40:49 volumio sudo[8337]: pam_unix(sudo:session): session closed for user root Oct 25 11:40:49 volumio volumio[793]: info: TidalConnect service stoped! Oct 25 11:40:50 volumio volumio[793]: info: TidalConnect service stoped! Oct 25 11:40:50 volumio volumio[793]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 25 11:40:50 volumio volumio[793]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 25 11:40:50 volumio sudo[8349]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 25 11:40:50 volumio sudo[8349]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 11:40:50 volumio systemd[1]: Started Volumio Tidal Connect Service. Oct 25 11:40:50 volumio sudo[8349]: pam_unix(sudo:session): session closed for user root Oct 25 11:40:50 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Oct 25 11:40:50 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Oct 25 11:40:50 volumio sudo[8355]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 25 11:40:50 volumio sudo[8355]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 11:40:50 volumio mpd[8339]: Oct 25 11:40 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 25 11:40:50 volumio systemd[1]: Started Music Player Daemon. Oct 25 11:40:50 volumio sudo[8358]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 25 11:40:50 volumio sudo[8358]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 11:40:50 volumio volumio[793]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 25 11:40:50 volumio systemd[1]: Stopping Music Player Daemon... Oct 25 11:40:50 volumio sudo[8249]: pam_unix(sudo:session): session closed for user root Oct 25 11:40:50 volumio sudo[8172]: pam_unix(sudo:session): session closed for user root Oct 25 11:40:50 volumio volumio[793]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 25 11:40:50 volumio sudo[8186]: pam_unix(sudo:session): session closed for user root Oct 25 11:40:50 volumio sudo[8292]: pam_unix(sudo:session): session closed for user root Oct 25 11:40:50 volumio sudo[8335]: pam_unix(sudo:session): session closed for user root Oct 25 11:40:50 volumio systemd[1]: mpd.service: Succeeded. Oct 25 11:40:50 volumio systemd[1]: Stopped Music Player Daemon. Oct 25 11:40:50 volumio volumio[793]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 25 11:40:50 volumio volumio[793]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 25 11:40:50 volumio systemd[1]: Starting Music Player Daemon... Oct 25 11:40:50 volumio volumio[793]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 25 11:40:50 volumio volumio[793]: info: Executing endpoint tc_getconfig Oct 25 11:40:50 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Oct 25 11:40:50 volumio vtcs[8351]: STARTING TidalConnect services, version: 1.5.1-beta1 Oct 25 11:40:50 volumio vtcs[8351]: STARTED TidalConnect services. Oct 25 11:40:50 volumio volumio[793]: info: Executing endpoint tc_connect Oct 25 11:40:50 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Oct 25 11:40:50 volumio volumio[793]: info: Connecting to TidalConnect Oct 25 11:40:50 volumio volumio[793]: info: CoreCommandRouter::servicePushState Oct 25 11:40:50 volumio volumio[793]: info: CoreStateMachine::pushState Oct 25 11:40:50 volumio volumio[793]: info: CorePlayQueue::getTrack 1 Oct 25 11:40:50 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 11:40:50 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Oct 25 11:40:50 volumio volumio[793]: info: MRS: Pushing multiroomSync output update for this device Oct 25 11:40:50 volumio volumio[793]: info: MRS: Pushing multiroomSync output Oct 25 11:40:50 volumio volumio[793]: info: CoreCommandRouter::volumioGetState Oct 25 11:40:50 volumio volumio[793]: info: CorePlayQueue::getTrack 1 Oct 25 11:40:50 volumio volumio[793]: info: CorePlayQueue::getTrack 1 Oct 25 11:40:50 volumio volumio[793]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Oct 25 11:40:50 volumio volumio[793]: info: CoreCommandRouter::servicePushState Oct 25 11:40:50 volumio volumio[793]: info: CoreStateMachine::pushState Oct 25 11:40:50 volumio volumio[793]: info: CorePlayQueue::getTrack 1 Oct 25 11:40:50 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Oct 25 11:40:50 volumio volumio[793]: info: MRS: Pushing multiroomSync output update for this device Oct 25 11:40:50 volumio volumio[793]: info: MRS: Pushing multiroomSync output Oct 25 11:40:50 volumio volumio[793]: info: CoreCommandRouter::volumioGetState Oct 25 11:40:50 volumio volumio[793]: info: CorePlayQueue::getTrack 1 Oct 25 11:40:50 volumio volumio[793]: info: CorePlayQueue::getTrack 1 Oct 25 11:40:50 volumio volumio[793]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Oct 25 11:40:50 volumio volumio[793]: info: [ASDebug] CurState: stop PrevState: stop Oct 25 11:40:50 volumio volumio[793]: info: [ASDebug] CurState: stop PrevState: stop Oct 25 11:40:50 volumio sudo[8362]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 25 11:40:50 volumio sudo[8362]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 11:40:50 volumio sudo[8362]: pam_unix(sudo:session): session closed for user root Oct 25 11:40:50 volumio volumio[793]: info: TidalConnect service stoped! Oct 25 11:40:50 volumio volumio[793]: info: TidalConnect service stoped! Oct 25 11:40:50 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Oct 25 11:40:50 volumio sudo[8382]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 25 11:40:50 volumio sudo[8382]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 11:40:50 volumio volumio[793]: info: TidalConnect service stoped! Oct 25 11:40:50 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 25 11:40:50 volumio systemd[1]: mpd.service: Succeeded. Oct 25 11:40:50 volumio systemd[1]: Stopped Music Player Daemon. Oct 25 11:40:50 volumio volumio[793]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 25 11:40:50 volumio volumio[793]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 25 11:40:50 volumio volumio[793]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 25 11:40:50 volumio volumio[793]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 25 11:40:50 volumio volumio[793]: info: TidalConnect service stoped! Oct 25 11:40:50 volumio systemd[1]: Starting Music Player Daemon... Oct 25 11:40:50 volumio volumio[793]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 25 11:40:50 volumio volumio[793]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 25 11:40:50 volumio volumio[793]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 25 11:40:50 volumio volumio[793]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 25 11:40:50 volumio volumio[793]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 25 11:40:50 volumio sudo[8402]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 25 11:40:50 volumio sudo[8402]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 11:40:50 volumio sudo[8402]: pam_unix(sudo:session): session closed for user root Oct 25 11:40:50 volumio sudo[8405]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 25 11:40:50 volumio sudo[8405]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 11:40:50 volumio sudo[8385]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 25 11:40:50 volumio sudo[8385]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 11:40:50 volumio sudo[8385]: pam_unix(sudo:session): session closed for user root Oct 25 11:40:50 volumio volumio[793]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Oct 25 11:40:50 volumio volumio[793]: info: CoreStateMachine::getcurrentVolume Oct 25 11:40:50 volumio volumio[793]: info: CoreCommandRouter::volumioRetrievevolume Oct 25 11:40:50 volumio sudo[8405]: pam_unix(sudo:session): session closed for user root Oct 25 11:40:51 volumio volumio[793]: info: CoreStateMachine::pushState Oct 25 11:40:51 volumio volumio[793]: info: CorePlayQueue::getTrack 1 Oct 25 11:40:51 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 11:40:51 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Oct 25 11:40:51 volumio volumio[793]: info: MRS: Pushing multiroomSync output update for this device Oct 25 11:40:51 volumio volumio[793]: info: MRS: Pushing multiroomSync output Oct 25 11:40:51 volumio volumio[793]: info: CoreCommandRouter::volumioGetState Oct 25 11:40:51 volumio volumio[793]: info: CorePlayQueue::getTrack 1 Oct 25 11:40:51 volumio volumio[793]: info: CoreStateMachine::updateTrackBlock Oct 25 11:40:51 volumio volumio[793]: info: CorePlayQueue::getTrackBlock Oct 25 11:40:51 volumio volumio[793]: info: CoreCommandRouter::volumioRetrievevolume Oct 25 11:40:51 volumio volumio[793]: info: [ASDebug] CurState: stop PrevState: stop Oct 25 11:40:51 volumio volumio[793]: info: CoreStateMachine::pushState Oct 25 11:40:51 volumio volumio[793]: info: CorePlayQueue::getTrack 1 Oct 25 11:40:51 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Oct 25 11:40:51 volumio volumio[793]: info: MRS: Pushing multiroomSync output update for this device Oct 25 11:40:51 volumio volumio[793]: info: MRS: Pushing multiroomSync output Oct 25 11:40:51 volumio volumio[793]: info: CoreCommandRouter::volumioGetState Oct 25 11:40:51 volumio volumio[793]: info: CorePlayQueue::getTrack 1 Oct 25 11:40:51 volumio volumio[793]: info: [ASDebug] CurState: stop PrevState: stop Oct 25 11:40:51 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Oct 25 11:40:51 volumio sudo[8438]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 25 11:40:51 volumio sudo[8438]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 11:40:51 volumio volumio[793]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 25 11:40:51 volumio volumio[793]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 25 11:40:51 volumio volumio[793]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 25 11:40:51 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 25 11:40:51 volumio systemd[1]: mpd.service: Succeeded. Oct 25 11:40:51 volumio systemd[1]: Stopped Music Player Daemon. Oct 25 11:40:51 volumio volumio[793]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 25 11:40:51 volumio systemd[1]: Starting Music Player Daemon... Oct 25 11:40:51 volumio volumio[793]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 25 11:40:51 volumio sudo[8440]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 25 11:40:51 volumio sudo[8440]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 11:40:51 volumio sudo[8440]: pam_unix(sudo:session): session closed for user root Oct 25 11:40:51 volumio volumio[793]: info: TidalConnect service stoped! Oct 25 11:40:51 volumio volumio[793]: info: TidalConnect service stoped! Oct 25 11:40:51 volumio volumio[793]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 25 11:40:51 volumio volumio[793]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 25 11:40:51 volumio sudo[8452]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 25 11:40:51 volumio sudo[8452]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 11:40:51 volumio sudo[8452]: pam_unix(sudo:session): session closed for user root Oct 25 11:40:51 volumio volumio[793]: info: TidalConnect service stoped! Oct 25 11:40:52 volumio volumio[793]: info: TidalConnect service stoped! Oct 25 11:40:52 volumio mpd[8442]: Oct 25 11:40 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 25 11:40:52 volumio volumio[793]: error: MPD error: The expression evaluated to a falsy value: Oct 25 11:40:52 volumio volumio[793]: assert.ok(self.idling) Oct 25 11:40:52 volumio systemd[1]: Started Music Player Daemon. Oct 25 11:40:52 volumio volumio[793]: error: The expression evaluated to a falsy value: Oct 25 11:40:52 volumio volumio[793]: assert.ok(self.idling) Oct 25 11:40:52 volumio sudo[8438]: pam_unix(sudo:session): session closed for user root Oct 25 11:40:52 volumio sudo[8382]: pam_unix(sudo:session): session closed for user root Oct 25 11:40:52 volumio sudo[8358]: pam_unix(sudo:session): session closed for user root Oct 25 11:40:52 volumio sudo[8355]: pam_unix(sudo:session): session closed for user root Oct 25 11:40:52 volumio volumio[793]: error: MPD error: The expression evaluated to a falsy value: Oct 25 11:40:52 volumio volumio[793]: assert.ok(self.idling) Oct 25 11:40:52 volumio volumio[793]: error: The expression evaluated to a falsy value: Oct 25 11:40:52 volumio volumio[793]: assert.ok(self.idling) Oct 25 11:40:52 volumio volumio[793]: error: MPD error: The expression evaluated to a falsy value: Oct 25 11:40:52 volumio volumio[793]: assert.ok(self.idling) Oct 25 11:40:52 volumio volumio[793]: error: The expression evaluated to a falsy value: Oct 25 11:40:52 volumio volumio[793]: assert.ok(self.idling) Oct 25 11:40:52 volumio volumio[793]: error: MPD error: The expression evaluated to a falsy value: Oct 25 11:40:52 volumio volumio[793]: assert.ok(self.idling) Oct 25 11:40:52 volumio volumio[793]: error: The expression evaluated to a falsy value: Oct 25 11:40:52 volumio volumio[793]: assert.ok(self.idling) Oct 25 11:40:52 volumio volumio[793]: error: updateQueue error: null Oct 25 11:40:52 volumio volumio[793]: error: MPD error: The expression evaluated to a falsy value: Oct 25 11:40:52 volumio volumio[793]: assert.ok(self.idling) Oct 25 11:40:52 volumio volumio[793]: error: The expression evaluated to a falsy value: Oct 25 11:40:52 volumio volumio[793]: assert.ok(self.idling) Oct 25 11:40:52 volumio volumio[793]: error: MPD error: The expression evaluated to a falsy value: Oct 25 11:40:52 volumio volumio[793]: assert.ok(self.idling) Oct 25 11:40:52 volumio volumio[793]: error: The expression evaluated to a falsy value: Oct 25 11:40:52 volumio volumio[793]: assert.ok(self.idling) Oct 25 11:40:52 volumio volumio[793]: error: MPD error: The expression evaluated to a falsy value: Oct 25 11:40:52 volumio volumio[793]: assert.ok(self.idling) Oct 25 11:40:52 volumio volumio[793]: error: The expression evaluated to a falsy value: Oct 25 11:40:52 volumio volumio[793]: assert.ok(self.idling) Oct 25 11:40:52 volumio volumio[793]: error: updateQueue error: null Oct 25 11:40:52 volumio volumio[793]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 25 11:40:52 volumio volumio[793]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 25 11:40:52 volumio sudo[8467]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 25 11:40:52 volumio sudo[8467]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 11:40:52 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Oct 25 11:40:52 volumio volumio[793]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 25 11:40:52 volumio sudo[8467]: pam_unix(sudo:session): session closed for user root Oct 25 11:40:52 volumio volumio[8461]: cset: --> shielding system active with Oct 25 11:40:52 volumio volumio[8461]: cset: "system" cpuset of CPUSPEC(1-3) with 169 tasks running Oct 25 11:40:52 volumio volumio[8461]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Oct 25 11:40:52 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Oct 25 11:40:53 volumio volumio[793]: info: TidalConnect service started! Oct 25 11:40:53 volumio volumio[793]: info: TidalConnect service started! Oct 25 11:40:54 volumio volumio[793]: info: TidalConnect service started! Oct 25 11:40:54 volumio volumio[793]: info: TidalConnect service started! Oct 25 11:40:55 volumio volumio[793]: info: TidalConnect service started! Oct 25 11:41:00 volumio volumio[793]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________ Oct 25 11:41:00 volumio volumio[793]: info: PLUGIN onReboot : networkfs Oct 25 11:41:00 volumio volumio[793]: info: PLUGIN onReboot : audiophonicsonoff Oct 25 11:41:00 volumio volumio[793]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 25 11:41:00 volumio volumio[793]: TypeError: Cannot read property 'writeSync' of undefined Oct 25 11:41:00 volumio volumio[793]: at ControllerAudiophonicsOnOff.onVolumioReboot (/data/plugins/system_controller/audiophonicsonoff/index.js:35:25) Oct 25 11:41:00 volumio volumio[793]: at PluginManager.onVolumioRebootPlugin (/volumio/app/pluginmanager.js:684:30) Oct 25 11:41:00 volumio volumio[793]: at HashMap. (/volumio/app/pluginmanager.js:668:31) Oct 25 11:41:00 volumio volumio[793]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Oct 25 11:41:00 volumio volumio[793]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Oct 25 11:41:00 volumio volumio[793]: at PluginManager.onVolumioReboot (/volumio/app/pluginmanager.js:666:20) Oct 25 11:41:00 volumio volumio[793]: at CoreCommandRouter.reboot (/volumio/app/index.js:1350:22) Oct 25 11:41:00 volumio volumio[793]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:870:33) Oct 25 11:41:00 volumio volumio[793]: at Socket.emit (events.js:315:20) Oct 25 11:41:00 volumio volumio[793]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Oct 25 11:41:00 volumio volumio[793]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Oct 25 11:41:00 volumio volumio[793]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 25 11:41:00 volumio sudo[8534]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-25 11:40 Oct 25 11:41:00 volumio sudo[8534]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"