-- Logs begin at Thu 2019-02-14 02:11:58 PST, end at Tue 2025-03-04 22:25:04 PST. -- Mar 04 22:24:01 hi-fi-ms volumio[1065]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Mar 04 22:24:02 hi-fi-ms volumio[1065]: info: browseTIDALUri took 330 milliseconds Mar 04 22:24:02 hi-fi-ms volumio[1065]: info: Preload queue cleared Mar 04 22:24:02 hi-fi-ms volumio[1065]: info: Preloading song: tidal://song/333884571 Mar 04 22:24:02 hi-fi-ms volumio[1065]: info: Preloading song: tidal://song/333884572 Mar 04 22:24:02 hi-fi-ms volumio[1065]: info: Preloading song: tidal://song/333884573 Mar 04 22:24:02 hi-fi-ms volumio[1065]: info: Preloading song: tidal://song/333884574 Mar 04 22:24:02 hi-fi-ms volumio[1065]: info: Preloading song: tidal://song/333884575 Mar 04 22:24:02 hi-fi-ms volumio[1065]: info: Preloading song: tidal://song/333884576 Mar 04 22:24:02 hi-fi-ms volumio[1065]: info: Preloading song: tidal://song/333884577 Mar 04 22:24:02 hi-fi-ms volumio[1065]: info: Preloading song: tidal://song/333884578 Mar 04 22:24:02 hi-fi-ms volumio[1065]: info: Preloading song: tidal://song/333884579 Mar 04 22:24:02 hi-fi-ms volumio[1065]: info: Preloading song: tidal://song/333884580 Mar 04 22:24:02 hi-fi-ms volumio[1065]: info: Preloading song: tidal://song/333884581 Mar 04 22:24:02 hi-fi-ms volumio[1065]: info: Exploding uri tidal://song/333884571 in service tidal Mar 04 22:24:02 hi-fi-ms volumio[1065]: info: Exploding uri tidal://song/333884572 in service tidal Mar 04 22:24:02 hi-fi-ms volumio[1065]: info: Exploding uri tidal://song/333884573 in service tidal Mar 04 22:24:02 hi-fi-ms volumio[1065]: info: Executing endpoint getSimilarAlbums Mar 04 22:24:02 hi-fi-ms volumio[1065]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Mar 04 22:24:02 hi-fi-ms volumio[1065]: info: Executing endpoint getSimilarAlbums Mar 04 22:24:02 hi-fi-ms volumio[1065]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Mar 04 22:24:02 hi-fi-ms volumio[1065]: info: Exploding uri tidal://song/333884574 in service tidal Mar 04 22:24:02 hi-fi-ms volumio[1065]: info: Exploding uri tidal://song/333884575 in service tidal Mar 04 22:24:02 hi-fi-ms volumio[1065]: info: Executing endpoint metavolumio Mar 04 22:24:02 hi-fi-ms volumio[1065]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 04 22:24:02 hi-fi-ms volumio[1065]: info: Executing endpoint metavolumio Mar 04 22:24:02 hi-fi-ms volumio[1065]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 04 22:24:02 hi-fi-ms volumio[1065]: info: Executing endpoint metavolumio Mar 04 22:24:02 hi-fi-ms volumio[1065]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 04 22:24:02 hi-fi-ms volumio[1065]: info: Executing endpoint metavolumio Mar 04 22:24:02 hi-fi-ms volumio[1065]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 04 22:24:02 hi-fi-ms volumio[1065]: info: Exploding uri tidal://song/333884576 in service tidal Mar 04 22:24:02 hi-fi-ms volumio[1065]: info: explodeTIDALUri took 259 milliseconds Mar 04 22:24:02 hi-fi-ms volumio[1065]: info: explodeTIDALUri took 240 milliseconds Mar 04 22:24:02 hi-fi-ms volumio[1065]: info: Exploding uri tidal://song/333884577 in service tidal Mar 04 22:24:02 hi-fi-ms volumio[1065]: info: explodeTIDALUri took 240 milliseconds Mar 04 22:24:02 hi-fi-ms volumio[1065]: info: Exploding uri tidal://song/333884578 in service tidal Mar 04 22:24:02 hi-fi-ms volumio[1065]: info: explodeTIDALUri took 227 milliseconds Mar 04 22:24:02 hi-fi-ms volumio[1065]: info: Exploding uri tidal://song/333884579 in service tidal Mar 04 22:24:02 hi-fi-ms volumio[1065]: info: explodeTIDALUri took 242 milliseconds Mar 04 22:24:02 hi-fi-ms volumio[1065]: info: Exploding uri tidal://song/333884580 in service tidal Mar 04 22:24:02 hi-fi-ms volumio[1065]: info: Exploding uri tidal://song/333884581 in service tidal Mar 04 22:24:02 hi-fi-ms volumio[1065]: info: explodeTIDALUri took 258 milliseconds Mar 04 22:24:02 hi-fi-ms volumio[1065]: info: explodeTIDALUri took 230 milliseconds Mar 04 22:24:02 hi-fi-ms volumio[1065]: info: explodeTIDALUri took 238 milliseconds Mar 04 22:24:02 hi-fi-ms volumio[1065]: info: explodeTIDALUri took 239 milliseconds Mar 04 22:24:02 hi-fi-ms volumio[1065]: info: explodeTIDALUri took 244 milliseconds Mar 04 22:24:02 hi-fi-ms volumio[1065]: info: explodeTIDALUri took 250 milliseconds Mar 04 22:24:03 hi-fi-ms volumio[1065]: info: Preload queue cleared Mar 04 22:24:03 hi-fi-ms volumio[1065]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 04 22:24:03 hi-fi-ms volumio[1065]: info: CoreStateMachine::ClearQueue Mar 04 22:24:03 hi-fi-ms volumio[1065]: info: CoreStateMachine::stop Mar 04 22:24:03 hi-fi-ms volumio[1065]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 22:24:03 hi-fi-ms volumio[1065]: info: CorePlayQueue::clearPlayQueue Mar 04 22:24:03 hi-fi-ms volumio[1065]: info: CorePlayQueue::saveQueue Mar 04 22:24:03 hi-fi-ms volumio[1065]: info: CoreCommandRouter::volumioPushQueue Mar 04 22:24:03 hi-fi-ms volumio[1065]: info: CoreStateMachine::addQueueItems Mar 04 22:24:03 hi-fi-ms volumio[1065]: info: CorePlayQueue::addQueueItems Mar 04 22:24:03 hi-fi-ms volumio[1065]: info: Preload queue cleared Mar 04 22:24:03 hi-fi-ms volumio[1065]: info: Adding Item to queue: tidal://album/333884569 Mar 04 22:24:03 hi-fi-ms volumio[1065]: info: Exploding uri tidal://album/333884569 in service tidal Mar 04 22:24:03 hi-fi-ms volumio[1065]: info: explodeTIDALUri took 243 milliseconds Mar 04 22:24:03 hi-fi-ms volumio[1065]: info: CoreCommandRouter::volumioPushQueue Mar 04 22:24:03 hi-fi-ms volumio[1065]: info: CorePlayQueue::saveQueue Mar 04 22:24:03 hi-fi-ms volumio[1065]: info: CoreStateMachine::updateTrackBlock Mar 04 22:24:03 hi-fi-ms volumio[1065]: info: CorePlayQueue::getTrackBlock Mar 04 22:24:03 hi-fi-ms volumio[1065]: info: CoreCommandRouter::volumioPlay Mar 04 22:24:03 hi-fi-ms volumio[1065]: info: CoreStateMachine::play index 0 Mar 04 22:24:03 hi-fi-ms volumio[1065]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 22:24:03 hi-fi-ms volumio[1065]: info: CoreStateMachine::stop Mar 04 22:24:03 hi-fi-ms volumio[1065]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 22:24:03 hi-fi-ms volumio[1065]: info: CoreStateMachine::play index undefined Mar 04 22:24:03 hi-fi-ms volumio[1065]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 22:24:03 hi-fi-ms volumio[1065]: info: CorePlayQueue::getTrack 0 Mar 04 22:24:03 hi-fi-ms volumio[1065]: info: CoreStateMachine::startPlaybackTimer Mar 04 22:24:03 hi-fi-ms volumio[1065]: info: CorePlayQueue::getTrack 0 Mar 04 22:24:03 hi-fi-ms volumio[1065]: info: [1741155843666] ControllerTidal::clearAddPlayTrack Mar 04 22:24:03 hi-fi-ms volumio[1065]: info: Getting stream with soundQuality LOSSLESS Mar 04 22:24:03 hi-fi-ms volumio[1065]: verbose: ControllerMpd::sendMpdCommand stop Mar 04 22:24:03 hi-fi-ms volumio[1065]: info: sendMpdCommand stop took 1 milliseconds Mar 04 22:24:03 hi-fi-ms volumio[1065]: verbose: ControllerMpd::sendMpdCommand clear Mar 04 22:24:03 hi-fi-ms volumio[1065]: info: Mar 04 22:24:03 hi-fi-ms volumio[1065]: ---------------------------- MPD announces system playlist update Mar 04 22:24:03 hi-fi-ms volumio[1065]: info: Ignoring MPD Status Update Mar 04 22:24:03 hi-fi-ms volumio[1065]: info: sendMpdCommand clear took 0 milliseconds Mar 04 22:24:03 hi-fi-ms volumio[1065]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/333884570&soundQuality=LOSSLESS" Mar 04 22:24:03 hi-fi-ms volumio[1065]: info: Mar 04 22:24:03 hi-fi-ms volumio[1065]: ---------------------------- MPD announces system playlist update Mar 04 22:24:03 hi-fi-ms volumio[1065]: info: Ignoring MPD Status Update Mar 04 22:24:03 hi-fi-ms volumio[1065]: error: updateQueue error: null Mar 04 22:24:03 hi-fi-ms volumio[1065]: info: Mar 04 22:24:03 hi-fi-ms volumio[1065]: ---------------------------- MPD announces system playlist update Mar 04 22:24:03 hi-fi-ms volumio[1065]: info: Ignoring MPD Status Update Mar 04 22:24:03 hi-fi-ms volumio[1065]: info: ------------------------------ 0ms Mar 04 22:24:03 hi-fi-ms volumio[1065]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/333884570&soundQuality=LOSSLESS" took 0 milliseconds Mar 04 22:24:03 hi-fi-ms volumio[1065]: info: ------------------------------ 0ms Mar 04 22:24:03 hi-fi-ms volumio[1065]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 04 22:24:03 hi-fi-ms volumio[1065]: verbose: ControllerMpd::sendMpdCommand play Mar 04 22:24:03 hi-fi-ms volumio[1065]: info: Mar 04 22:24:03 hi-fi-ms volumio[1065]: ---------------------------- MPD announces system playlist update Mar 04 22:24:03 hi-fi-ms volumio[1065]: info: Ignoring MPD Status Update Mar 04 22:24:03 hi-fi-ms volumio[1065]: info: ------------------------------ 1ms Mar 04 22:24:03 hi-fi-ms volumio[1065]: info: sendMpdCommand play took 1 milliseconds Mar 04 22:24:03 hi-fi-ms volumio[1065]: info: ------------------------------ 1ms Mar 04 22:24:03 hi-fi-ms volumio[1065]: STREAMING PROXY: Handling url /?data=tidal://song/333884570&soundQuality=LOSSLESS Mar 04 22:24:03 hi-fi-ms volumio[1065]: info: Executing endpoint getStreamUrltidal Mar 04 22:24:03 hi-fi-ms volumio[1065]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Mar 04 22:24:03 hi-fi-ms volumio[1065]: info: getStreamUrl took 250 milliseconds Mar 04 22:24:03 hi-fi-ms volumio[1065]: STREAMING PROXY: Got real url: http://sp-pr-cf.audio.tidal.com/mediatracks/CAEaKwgDEic3NTA5OGZjNzhhNTg4MzM2MGI1YWNkMWZlYzlkNDExOF82MS5tcDQ/0.flac?Expires=1741159443&Signature=CL9Vm1tWOsXovQ8TvNfLB1SBDCwKpHE0xTFmCSj2zzFlInZ~PXh26MX0tOY8HONwYQVzkXvhb8OMKoppKPuivh9StJXkZ1HCZXoBW~gAfNLajYmdL-c2-2omqQ0opbUeB9H6uBRBfjSjicGnJ1yLrAgub~m9uyJ0NnePKAiSQMoBnnGalkFE16aeqzyanep1gvl2VHDieVfXXEZInSR4qnjjeuMBrlinIiCopdQOTzDUlRjqF9svsLHz8vaex8PO9Ugoc38wGn1LsFJN0HjwnnzsXZXBPwqiCQ-xZOzKxBtEoFAu51V7Vh55G8dil4ljxNZEWET08dJkQ8txasR5gQ__&Key-Pair-Id=K14LZCZ9QUI4JL Mar 04 22:24:04 hi-fi-ms volumio[1065]: STREAMING PROXY: Response: 200, length: 30089451 Mar 04 22:24:04 hi-fi-ms volumio[1065]: info: Mar 04 22:24:04 hi-fi-ms volumio[1065]: ---------------------------- MPD announces state update: player Mar 04 22:24:04 hi-fi-ms volumio[1065]: info: ControllerMpd::getState Mar 04 22:24:04 hi-fi-ms volumio[1065]: verbose: ControllerMpd::sendMpdCommand status Mar 04 22:24:04 hi-fi-ms volumio[1065]: info: Mar 04 22:24:04 hi-fi-ms volumio[1065]: ---------------------------- MPD announces state update: player Mar 04 22:24:04 hi-fi-ms volumio[1065]: info: ControllerMpd::getState Mar 04 22:24:04 hi-fi-ms volumio[1065]: verbose: ControllerMpd::sendMpdCommand status Mar 04 22:24:04 hi-fi-ms volumio[1065]: info: Mar 04 22:24:04 hi-fi-ms volumio[1065]: ---------------------------- MPD announces state update: player Mar 04 22:24:04 hi-fi-ms volumio[1065]: info: ControllerMpd::getState Mar 04 22:24:04 hi-fi-ms volumio[1065]: verbose: ControllerMpd::sendMpdCommand status Mar 04 22:24:04 hi-fi-ms volumio[1065]: verbose: ControllerMpd::sendMpdCommand clearerror Mar 04 22:24:04 hi-fi-ms volumio[1065]: info: sendMpdCommand status took 2 milliseconds Mar 04 22:24:04 hi-fi-ms volumio[1065]: verbose: ControllerMpd::parseState Mar 04 22:24:04 hi-fi-ms volumio[1065]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 22:24:04 hi-fi-ms volumio[1065]: info: Mar 04 22:24:04 hi-fi-ms volumio[1065]: ---------------------------- MPD announces state update: player Mar 04 22:24:04 hi-fi-ms volumio[1065]: info: ControllerMpd::getState Mar 04 22:24:04 hi-fi-ms volumio[1065]: verbose: ControllerMpd::sendMpdCommand status Mar 04 22:24:04 hi-fi-ms volumio[1065]: verbose: ControllerMpd::sendMpdCommand clearerror Mar 04 22:24:04 hi-fi-ms volumio[1065]: info: sendMpdCommand status took 2 milliseconds Mar 04 22:24:04 hi-fi-ms volumio[1065]: verbose: ControllerMpd::sendMpdCommand clearerror Mar 04 22:24:04 hi-fi-ms volumio[1065]: info: sendMpdCommand status took 2 milliseconds Mar 04 22:24:04 hi-fi-ms volumio[1065]: info: sendMpdCommand clearerror took 1 milliseconds Mar 04 22:24:04 hi-fi-ms volumio[1065]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 04 22:24:04 hi-fi-ms volumio[1065]: verbose: ControllerMpd::parseState Mar 04 22:24:04 hi-fi-ms volumio[1065]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 22:24:04 hi-fi-ms volumio[1065]: verbose: ControllerMpd::parseState Mar 04 22:24:04 hi-fi-ms volumio[1065]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 22:24:04 hi-fi-ms volumio[1065]: verbose: ControllerMpd::parseTrackInfo Mar 04 22:24:04 hi-fi-ms volumio[1065]: info: ControllerMpd::pushState Mar 04 22:24:04 hi-fi-ms volumio[1065]: info: CoreCommandRouter::servicePushState Mar 04 22:24:04 hi-fi-ms volumio[1065]: info: CorePlayQueue::getTrack 0 Mar 04 22:24:04 hi-fi-ms volumio[1065]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":238,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"333884570&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/333884570&soundQuality=LOSSLESS","trackType":"tidal"} Mar 04 22:24:04 hi-fi-ms volumio[1065]: verbose: CURRENT POSITION 0 Mar 04 22:24:04 hi-fi-ms volumio[1065]: info: CoreStateMachine::syncState stateService pause Mar 04 22:24:04 hi-fi-ms volumio[1065]: info: CoreStateMachine::syncState currentStatus stop Mar 04 22:24:04 hi-fi-ms volumio[1065]: info: CoreStateMachine::pushState Mar 04 22:24:04 hi-fi-ms volumio[1065]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 22:24:04 hi-fi-ms volumio[1065]: info: CoreCommandRouter::volumioPushState Mar 04 22:24:04 hi-fi-ms volumio[1065]: info: MRS: Pushing multiroomSync output update for this device Mar 04 22:24:04 hi-fi-ms volumio[1065]: info: MRS: Pushing multiroomSync output Mar 04 22:24:04 hi-fi-ms volumio[1065]: info: ------------------------------ 6ms Mar 04 22:24:04 hi-fi-ms volumio[1065]: info: sendMpdCommand status took 5 milliseconds Mar 04 22:24:04 hi-fi-ms volumio[1065]: info: sendMpdCommand clearerror took 4 milliseconds Mar 04 22:24:04 hi-fi-ms volumio[1065]: info: sendMpdCommand clearerror took 4 milliseconds Mar 04 22:24:04 hi-fi-ms volumio[1065]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 04 22:24:04 hi-fi-ms volumio[1065]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 04 22:24:04 hi-fi-ms volumio[1065]: verbose: ControllerMpd::parseState Mar 04 22:24:04 hi-fi-ms volumio[1065]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 22:24:04 hi-fi-ms volumio[1065]: verbose: ControllerMpd::parseTrackInfo Mar 04 22:24:04 hi-fi-ms volumio[1065]: verbose: ControllerMpd::parseTrackInfo Mar 04 22:24:04 hi-fi-ms volumio[1065]: info: ControllerMpd::pushState Mar 04 22:24:04 hi-fi-ms volumio[1065]: info: CoreCommandRouter::servicePushState Mar 04 22:24:04 hi-fi-ms volumio[1065]: info: CorePlayQueue::getTrack 0 Mar 04 22:24:04 hi-fi-ms volumio[1065]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":238,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"333884570&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/333884570&soundQuality=LOSSLESS","trackType":"tidal"} Mar 04 22:24:04 hi-fi-ms volumio[1065]: verbose: CURRENT POSITION 0 Mar 04 22:24:04 hi-fi-ms volumio[1065]: info: CoreStateMachine::syncState stateService pause Mar 04 22:24:04 hi-fi-ms volumio[1065]: info: CoreStateMachine::syncState currentStatus stop Mar 04 22:24:04 hi-fi-ms volumio[1065]: info: CoreStateMachine::pushState Mar 04 22:24:04 hi-fi-ms volumio[1065]: info: CoreCommandRouter::volumioPushState Mar 04 22:24:04 hi-fi-ms volumio[1065]: info: MRS: Pushing multiroomSync output update for this device Mar 04 22:24:04 hi-fi-ms volumio[1065]: info: MRS: Pushing multiroomSync output Mar 04 22:24:04 hi-fi-ms volumio[1065]: info: ControllerMpd::pushState Mar 04 22:24:04 hi-fi-ms volumio[1065]: info: CoreCommandRouter::servicePushState Mar 04 22:24:04 hi-fi-ms volumio[1065]: info: CorePlayQueue::getTrack 0 Mar 04 22:24:04 hi-fi-ms volumio[1065]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":238,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"333884570&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/333884570&soundQuality=LOSSLESS","trackType":"tidal"} Mar 04 22:24:04 hi-fi-ms volumio[1065]: verbose: CURRENT POSITION 0 Mar 04 22:24:04 hi-fi-ms volumio[1065]: info: CoreStateMachine::syncState stateService pause Mar 04 22:24:04 hi-fi-ms volumio[1065]: info: CoreStateMachine::syncState currentStatus stop Mar 04 22:24:04 hi-fi-ms volumio[1065]: info: CoreStateMachine::pushState Mar 04 22:24:04 hi-fi-ms volumio[1065]: info: CoreCommandRouter::volumioPushState Mar 04 22:24:04 hi-fi-ms volumio[1065]: info: MRS: Pushing multiroomSync output update for this device Mar 04 22:24:04 hi-fi-ms volumio[1065]: info: MRS: Pushing multiroomSync output Mar 04 22:24:04 hi-fi-ms volumio[1065]: info: ------------------------------ 10ms Mar 04 22:24:04 hi-fi-ms volumio[1065]: info: ------------------------------ 10ms Mar 04 22:24:04 hi-fi-ms volumio[1065]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 04 22:24:04 hi-fi-ms volumio[1065]: verbose: ControllerMpd::parseTrackInfo Mar 04 22:24:04 hi-fi-ms volumio[1065]: info: ControllerMpd::pushState Mar 04 22:24:04 hi-fi-ms volumio[1065]: info: CoreCommandRouter::servicePushState Mar 04 22:24:04 hi-fi-ms volumio[1065]: info: CorePlayQueue::getTrack 0 Mar 04 22:24:04 hi-fi-ms volumio[1065]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":238,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"333884570&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/333884570&soundQuality=LOSSLESS","trackType":"tidal"} Mar 04 22:24:04 hi-fi-ms volumio[1065]: verbose: CURRENT POSITION 0 Mar 04 22:24:04 hi-fi-ms volumio[1065]: info: CoreStateMachine::syncState stateService pause Mar 04 22:24:04 hi-fi-ms volumio[1065]: info: CoreStateMachine::syncState currentStatus stop Mar 04 22:24:04 hi-fi-ms volumio[1065]: info: CoreStateMachine::pushState Mar 04 22:24:04 hi-fi-ms volumio[1065]: info: CoreCommandRouter::volumioPushState Mar 04 22:24:04 hi-fi-ms volumio[1065]: info: MRS: Pushing multiroomSync output update for this device Mar 04 22:24:04 hi-fi-ms volumio[1065]: info: MRS: Pushing multiroomSync output Mar 04 22:24:04 hi-fi-ms volumio[1065]: info: ------------------------------ 10ms Mar 04 22:24:04 hi-fi-ms volumio[1065]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.5DozcAQwKSZgdmarQnkBoA0k1gf2.8ca8e6b9b16c0c428481f848ff69c0d9.state.status' Mar 04 22:24:04 hi-fi-ms volumio[1065]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.5DozcAQwKSZgdmarQnkBoA0k1gf2.8ca8e6b9b16c0c428481f848ff69c0d9.state.status' Mar 04 22:24:04 hi-fi-ms volumio[1065]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.5DozcAQwKSZgdmarQnkBoA0k1gf2.8ca8e6b9b16c0c428481f848ff69c0d9.state.status' Mar 04 22:24:05 hi-fi-ms volumio[1065]: STREAMING PROXY: Client dropped request, destroying Mar 04 22:24:06 hi-fi-ms volumio[1065]: info: Executing endpoint metavolumio Mar 04 22:24:06 hi-fi-ms volumio[1065]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: Preload queue cleared Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: CoreStateMachine::ClearQueue Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: CoreStateMachine::stop Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: CorePlayQueue::clearPlayQueue Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: CorePlayQueue::saveQueue Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: CoreCommandRouter::volumioPushQueue Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: CoreStateMachine::addQueueItems Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: CorePlayQueue::addQueueItems Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: Preload queue cleared Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: Adding Item to queue: tidal://album/333884569 Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: Using cached record of: tidal://album/333884569 Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: CoreCommandRouter::volumioPushQueue Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: CorePlayQueue::saveQueue Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: CoreStateMachine::updateTrackBlock Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: CorePlayQueue::getTrackBlock Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: CoreCommandRouter::volumioPlay Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: CoreStateMachine::play index 0 Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: CoreStateMachine::stop Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: CoreStateMachine::play index undefined Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: CorePlayQueue::getTrack 0 Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: CoreStateMachine::startPlaybackTimer Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: CorePlayQueue::getTrack 0 Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: [1741155847275] ControllerTidal::clearAddPlayTrack Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: Getting stream with soundQuality LOSSLESS Mar 04 22:24:07 hi-fi-ms volumio[1065]: verbose: ControllerMpd::sendMpdCommand stop Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: Mar 04 22:24:07 hi-fi-ms volumio[1065]: ---------------------------- MPD announces state update: player Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: sendMpdCommand stop took 1 milliseconds Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: ControllerMpd::getState Mar 04 22:24:07 hi-fi-ms volumio[1065]: verbose: ControllerMpd::sendMpdCommand status Mar 04 22:24:07 hi-fi-ms volumio[1065]: verbose: ControllerMpd::sendMpdCommand clear Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: Mar 04 22:24:07 hi-fi-ms volumio[1065]: ---------------------------- MPD announces state update: player Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: ControllerMpd::getState Mar 04 22:24:07 hi-fi-ms volumio[1065]: verbose: ControllerMpd::sendMpdCommand status Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: Mar 04 22:24:07 hi-fi-ms volumio[1065]: ---------------------------- MPD announces system playlist update Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: Ignoring MPD Status Update Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: sendMpdCommand status took 1 milliseconds Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: sendMpdCommand clear took 1 milliseconds Mar 04 22:24:07 hi-fi-ms volumio[1065]: verbose: ControllerMpd::parseState Mar 04 22:24:07 hi-fi-ms volumio[1065]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 22:24:07 hi-fi-ms volumio[1065]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/333884570&soundQuality=LOSSLESS" Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: Mar 04 22:24:07 hi-fi-ms volumio[1065]: ---------------------------- MPD announces system playlist update Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: Ignoring MPD Status Update Mar 04 22:24:07 hi-fi-ms volumio[1065]: error: updateQueue error: null Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: Mar 04 22:24:07 hi-fi-ms volumio[1065]: ---------------------------- MPD announces system playlist update Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: Ignoring MPD Status Update Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: sendMpdCommand status took 0 milliseconds Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: ------------------------------ 0ms Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/333884570&soundQuality=LOSSLESS" took 0 milliseconds Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: ------------------------------ 0ms Mar 04 22:24:07 hi-fi-ms volumio[1065]: verbose: ControllerMpd::parseState Mar 04 22:24:07 hi-fi-ms volumio[1065]: verbose: ControllerMpd::parseTrackInfo Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 04 22:24:07 hi-fi-ms volumio[1065]: verbose: ControllerMpd::sendMpdCommand play Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: ControllerMpd::pushState Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: CoreCommandRouter::servicePushState Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: CoreStateMachine::pushState Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: CoreCommandRouter::volumioPushState Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: MRS: Pushing multiroomSync output update for this device Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: MRS: Pushing multiroomSync output Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: CorePlayQueue::getTrack 0 Mar 04 22:24:07 hi-fi-ms volumio[1065]: 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} Mar 04 22:24:07 hi-fi-ms volumio[1065]: verbose: CURRENT POSITION 0 Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: CoreStateMachine::syncState stateService stop Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: CoreStateMachine::syncState currentStatus stop Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: CoreStateMachine::pushState Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: CoreCommandRouter::volumioPushState Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: MRS: Pushing multiroomSync output update for this device Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: MRS: Pushing multiroomSync output Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: No code Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: CoreStateMachine::pushState Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: CoreCommandRouter::volumioPushState Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: MRS: Pushing multiroomSync output update for this device Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: MRS: Pushing multiroomSync output Mar 04 22:24:07 hi-fi-ms volumio[1065]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 04 22:24:07 hi-fi-ms volumio[1065]: STREAMING PROXY: Handling url /?data=tidal://song/333884570&soundQuality=LOSSLESS Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: ------------------------------ 5ms Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: ------------------------------ 5ms Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: Mar 04 22:24:07 hi-fi-ms volumio[1065]: ---------------------------- MPD announces system playlist update Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: Ignoring MPD Status Update Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: ------------------------------ 5ms Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: sendMpdCommand play took 4 milliseconds Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: ------------------------------ 0ms Mar 04 22:24:07 hi-fi-ms volumio[1065]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.5DozcAQwKSZgdmarQnkBoA0k1gf2.8ca8e6b9b16c0c428481f848ff69c0d9.state.status' Mar 04 22:24:07 hi-fi-ms volumio[1065]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.5DozcAQwKSZgdmarQnkBoA0k1gf2.8ca8e6b9b16c0c428481f848ff69c0d9.state.status' Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: Executing endpoint getStreamUrltidal Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: getStreamUrl took 248 milliseconds Mar 04 22:24:07 hi-fi-ms volumio[1065]: STREAMING PROXY: Got real url: http://sp-pr-cf.audio.tidal.com/mediatracks/CAEaKwgDEic3NTA5OGZjNzhhNTg4MzM2MGI1YWNkMWZlYzlkNDExOF82MS5tcDQ/0.flac?Expires=1741159443&Signature=CL9Vm1tWOsXovQ8TvNfLB1SBDCwKpHE0xTFmCSj2zzFlInZ~PXh26MX0tOY8HONwYQVzkXvhb8OMKoppKPuivh9StJXkZ1HCZXoBW~gAfNLajYmdL-c2-2omqQ0opbUeB9H6uBRBfjSjicGnJ1yLrAgub~m9uyJ0NnePKAiSQMoBnnGalkFE16aeqzyanep1gvl2VHDieVfXXEZInSR4qnjjeuMBrlinIiCopdQOTzDUlRjqF9svsLHz8vaex8PO9Ugoc38wGn1LsFJN0HjwnnzsXZXBPwqiCQ-xZOzKxBtEoFAu51V7Vh55G8dil4ljxNZEWET08dJkQ8txasR5gQ__&Key-Pair-Id=K14LZCZ9QUI4JL Mar 04 22:24:07 hi-fi-ms volumio[1065]: STREAMING PROXY: Response: 200, length: 30089451 Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: Mar 04 22:24:07 hi-fi-ms volumio[1065]: ---------------------------- MPD announces state update: player Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: ControllerMpd::getState Mar 04 22:24:07 hi-fi-ms volumio[1065]: verbose: ControllerMpd::sendMpdCommand status Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: Mar 04 22:24:07 hi-fi-ms volumio[1065]: ---------------------------- MPD announces state update: player Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: ControllerMpd::getState Mar 04 22:24:07 hi-fi-ms volumio[1065]: verbose: ControllerMpd::sendMpdCommand status Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: Mar 04 22:24:07 hi-fi-ms volumio[1065]: ---------------------------- MPD announces state update: player Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: ControllerMpd::getState Mar 04 22:24:07 hi-fi-ms volumio[1065]: verbose: ControllerMpd::sendMpdCommand status Mar 04 22:24:07 hi-fi-ms volumio[1065]: verbose: ControllerMpd::sendMpdCommand clearerror Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: sendMpdCommand status took 1 milliseconds Mar 04 22:24:07 hi-fi-ms volumio[1065]: verbose: ControllerMpd::parseState Mar 04 22:24:07 hi-fi-ms volumio[1065]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: Mar 04 22:24:07 hi-fi-ms volumio[1065]: ---------------------------- MPD announces state update: player Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: ControllerMpd::getState Mar 04 22:24:07 hi-fi-ms volumio[1065]: verbose: ControllerMpd::sendMpdCommand status Mar 04 22:24:07 hi-fi-ms volumio[1065]: verbose: ControllerMpd::sendMpdCommand clearerror Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: sendMpdCommand status took 2 milliseconds Mar 04 22:24:07 hi-fi-ms volumio[1065]: verbose: ControllerMpd::sendMpdCommand clearerror Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: sendMpdCommand status took 1 milliseconds Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: sendMpdCommand clearerror took 1 milliseconds Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: sendMpdCommand status took 0 milliseconds Mar 04 22:24:07 hi-fi-ms volumio[1065]: verbose: ControllerMpd::parseState Mar 04 22:24:07 hi-fi-ms volumio[1065]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 22:24:07 hi-fi-ms volumio[1065]: verbose: ControllerMpd::parseState Mar 04 22:24:07 hi-fi-ms volumio[1065]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 22:24:07 hi-fi-ms volumio[1065]: verbose: ControllerMpd::parseTrackInfo Mar 04 22:24:07 hi-fi-ms volumio[1065]: verbose: ControllerMpd::parseState Mar 04 22:24:07 hi-fi-ms volumio[1065]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: ControllerMpd::pushState Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: CoreCommandRouter::servicePushState Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: CorePlayQueue::getTrack 0 Mar 04 22:24:07 hi-fi-ms volumio[1065]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":238,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"333884570&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/333884570&soundQuality=LOSSLESS","trackType":"tidal"} Mar 04 22:24:07 hi-fi-ms volumio[1065]: verbose: CURRENT POSITION 0 Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: CoreStateMachine::syncState stateService pause Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: CoreStateMachine::syncState currentStatus stop Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: CoreStateMachine::pushState Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: CoreCommandRouter::volumioPushState Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: MRS: Pushing multiroomSync output update for this device Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: MRS: Pushing multiroomSync output Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: ------------------------------ 6ms Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: sendMpdCommand clearerror took 5 milliseconds Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: sendMpdCommand clearerror took 5 milliseconds Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 04 22:24:07 hi-fi-ms volumio[1065]: verbose: ControllerMpd::parseTrackInfo Mar 04 22:24:07 hi-fi-ms volumio[1065]: verbose: ControllerMpd::parseTrackInfo Mar 04 22:24:07 hi-fi-ms volumio[1065]: verbose: ControllerMpd::parseTrackInfo Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: ControllerMpd::pushState Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: CoreCommandRouter::servicePushState Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: CorePlayQueue::getTrack 0 Mar 04 22:24:07 hi-fi-ms volumio[1065]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":238,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"333884570&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/333884570&soundQuality=LOSSLESS","trackType":"tidal"} Mar 04 22:24:07 hi-fi-ms volumio[1065]: verbose: CURRENT POSITION 0 Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: CoreStateMachine::syncState stateService pause Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: CoreStateMachine::syncState currentStatus stop Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: CoreStateMachine::pushState Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: CoreCommandRouter::volumioPushState Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: MRS: Pushing multiroomSync output update for this device Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: MRS: Pushing multiroomSync output Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: ControllerMpd::pushState Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: CoreCommandRouter::servicePushState Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: CorePlayQueue::getTrack 0 Mar 04 22:24:07 hi-fi-ms volumio[1065]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":238,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"333884570&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/333884570&soundQuality=LOSSLESS","trackType":"tidal"} Mar 04 22:24:07 hi-fi-ms volumio[1065]: verbose: CURRENT POSITION 0 Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: CoreStateMachine::syncState stateService pause Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: CoreStateMachine::syncState currentStatus stop Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: CoreStateMachine::pushState Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: CoreCommandRouter::volumioPushState Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: MRS: Pushing multiroomSync output update for this device Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: MRS: Pushing multiroomSync output Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: ControllerMpd::pushState Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: CoreCommandRouter::servicePushState Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: CorePlayQueue::getTrack 0 Mar 04 22:24:07 hi-fi-ms volumio[1065]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":238,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"333884570&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/333884570&soundQuality=LOSSLESS","trackType":"tidal"} Mar 04 22:24:07 hi-fi-ms volumio[1065]: verbose: CURRENT POSITION 0 Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: CoreStateMachine::syncState stateService pause Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: CoreStateMachine::syncState currentStatus stop Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: CoreStateMachine::pushState Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: CoreCommandRouter::volumioPushState Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: MRS: Pushing multiroomSync output update for this device Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: MRS: Pushing multiroomSync output Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: ------------------------------ 11ms Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: ------------------------------ 10ms Mar 04 22:24:07 hi-fi-ms volumio[1065]: info: ------------------------------ 10ms Mar 04 22:24:07 hi-fi-ms volumio[1065]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.5DozcAQwKSZgdmarQnkBoA0k1gf2.8ca8e6b9b16c0c428481f848ff69c0d9.state.status' Mar 04 22:24:07 hi-fi-ms volumio[1065]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.5DozcAQwKSZgdmarQnkBoA0k1gf2.8ca8e6b9b16c0c428481f848ff69c0d9.state.status' Mar 04 22:24:07 hi-fi-ms volumio[1065]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.5DozcAQwKSZgdmarQnkBoA0k1gf2.8ca8e6b9b16c0c428481f848ff69c0d9.state.status' Mar 04 22:24:08 hi-fi-ms volumio[1065]: STREAMING PROXY: Client dropped request, destroying Mar 04 22:24:09 hi-fi-ms volumio[1065]: info: Executing endpoint metavolumio Mar 04 22:24:09 hi-fi-ms volumio[1065]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 04 22:24:11 hi-fi-ms sudo[27407]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 04 22:24:11 hi-fi-ms sudo[27407]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 04 22:24:11 hi-fi-ms sudo[27407]: pam_unix(sudo:session): session closed for user root Mar 04 22:24:11 hi-fi-ms sudo[27410]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 04 22:24:11 hi-fi-ms sudo[27410]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 04 22:24:11 hi-fi-ms sudo[27410]: pam_unix(sudo:session): session closed for user root Mar 04 22:24:11 hi-fi-ms volumio[1065]: verbose: New Socket.io Connection to 10.0.0.219 from 10.0.0.161 UA: Mozilla/5.0 (Linux; Android 14; moto g 5G - 2024 Build/U1UFNS34.41-98-3-13; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/133.0.6943.137 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Mar 04 22:24:12 hi-fi-ms sudo[27415]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 04 22:24:12 hi-fi-ms sudo[27415]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 04 22:24:12 hi-fi-ms sudo[27415]: pam_unix(sudo:session): session closed for user root Mar 04 22:24:12 hi-fi-ms sudo[27418]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 04 22:24:12 hi-fi-ms sudo[27418]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 04 22:24:12 hi-fi-ms sudo[27418]: pam_unix(sudo:session): session closed for user root Mar 04 22:24:12 hi-fi-ms volumio[1065]: verbose: New Socket.io Connection to 10.0.0.219 from 10.0.0.161 UA: Mozilla/5.0 (Linux; Android 14; moto g 5G - 2024 Build/U1UFNS34.41-98-3-13; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/133.0.6943.137 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Mar 04 22:24:12 hi-fi-ms volumio[1065]: info: Executing endpoint metavolumio Mar 04 22:24:12 hi-fi-ms volumio[1065]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 04 22:24:12 hi-fi-ms volumio[1065]: info: Executing endpoint metavolumio Mar 04 22:24:12 hi-fi-ms volumio[1065]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 04 22:24:12 hi-fi-ms volumio[1065]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 04 22:24:12 hi-fi-ms volumio[1065]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 04 22:24:12 hi-fi-ms volumio[1065]: info: CoreCommandRouter::volumioGetVisibleSources Mar 04 22:24:12 hi-fi-ms volumio[1065]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 04 22:24:12 hi-fi-ms volumio[1065]: info: CoreCommandRouter::volumioGetState Mar 04 22:24:12 hi-fi-ms volumio[1065]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 04 22:24:12 hi-fi-ms volumio[1065]: info: CoreCommandRouter::volumioGetQueue Mar 04 22:24:12 hi-fi-ms volumio[1065]: info: CoreStateMachine::getQueue Mar 04 22:24:12 hi-fi-ms volumio[1065]: info: CorePlayQueue::getQueue Mar 04 22:24:12 hi-fi-ms volumio[1065]: info: Listing playlists Mar 04 22:24:12 hi-fi-ms volumio[1065]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 04 22:24:12 hi-fi-ms volumio[1065]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Mar 04 22:24:12 hi-fi-ms volumio[1065]: info: Received Get System Info Mar 04 22:24:12 hi-fi-ms volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 04 22:24:12 hi-fi-ms volumio[1065]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 04 22:24:12 hi-fi-ms volumio[1065]: info: Discovery: Getting this device information Mar 04 22:24:12 hi-fi-ms volumio[1065]: info: CoreCommandRouter::volumioGetState Mar 04 22:24:12 hi-fi-ms volumio[1065]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 04 22:24:12 hi-fi-ms volumio[1065]: info: CoreCommandRouter::volumioGetState Mar 04 22:24:12 hi-fi-ms volumio[1065]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 04 22:24:12 hi-fi-ms volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 04 22:24:14 hi-fi-ms volumio[1065]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 04 22:24:14 hi-fi-ms volumio[1065]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 04 22:24:14 hi-fi-ms volumio[1065]: info: Received Get System Info Mar 04 22:24:14 hi-fi-ms volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 04 22:24:14 hi-fi-ms volumio[1065]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 04 22:24:14 hi-fi-ms volumio[1065]: info: Discovery: Getting this device information Mar 04 22:24:14 hi-fi-ms volumio[1065]: info: CoreCommandRouter::volumioGetState Mar 04 22:24:14 hi-fi-ms volumio[1065]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 04 22:24:14 hi-fi-ms volumio[1065]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 04 22:24:14 hi-fi-ms volumio[1065]: info: Received Get System Info Mar 04 22:24:14 hi-fi-ms volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 04 22:24:14 hi-fi-ms volumio[1065]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 04 22:24:14 hi-fi-ms volumio[1065]: info: Discovery: Getting this device information Mar 04 22:24:14 hi-fi-ms volumio[1065]: info: CoreCommandRouter::volumioGetState Mar 04 22:24:14 hi-fi-ms volumio[1065]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 04 22:24:14 hi-fi-ms volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 04 22:24:14 hi-fi-ms volumio[1065]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 04 22:24:14 hi-fi-ms volumio[1065]: info: Discovery: Getting this device information Mar 04 22:24:14 hi-fi-ms volumio[1065]: info: CoreCommandRouter::volumioGetState Mar 04 22:24:14 hi-fi-ms volumio[1065]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 04 22:24:21 hi-fi-ms volumio[1065]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 04 22:24:21 hi-fi-ms volumio[1065]: /bin/cat: /sys/class/net/wlan0/flags: No such file or directory Mar 04 22:24:21 hi-fi-ms volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 04 22:24:21 hi-fi-ms volumio[1065]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Mar 04 22:24:21 hi-fi-ms sudo[27442]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Mar 04 22:24:21 hi-fi-ms sudo[27442]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 04 22:24:21 hi-fi-ms sudo[27448]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Mar 04 22:24:21 hi-fi-ms sudo[27448]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 04 22:24:21 hi-fi-ms sudo[27457]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Mar 04 22:24:21 hi-fi-ms sudo[27457]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 04 22:24:21 hi-fi-ms sudo[27463]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Mar 04 22:24:22 hi-fi-ms sudo[27463]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 04 22:24:22 hi-fi-ms sudo[27471]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 04 22:24:22 hi-fi-ms sudo[27471]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 04 22:24:22 hi-fi-ms sudo[27471]: pam_unix(sudo:session): session closed for user root Mar 04 22:24:22 hi-fi-ms volumio[1065]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Mar 04 22:24:22 hi-fi-ms volumio[1065]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Mar 04 22:24:22 hi-fi-ms sudo[27474]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 04 22:24:22 hi-fi-ms sudo[27474]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 04 22:24:22 hi-fi-ms sudo[27474]: pam_unix(sudo:session): session closed for user root Mar 04 22:24:22 hi-fi-ms sudo[27442]: pam_unix(sudo:session): session closed for user root Mar 04 22:24:22 hi-fi-ms sudo[27448]: pam_unix(sudo:session): session closed for user root Mar 04 22:24:22 hi-fi-ms sudo[27463]: pam_unix(sudo:session): session closed for user root Mar 04 22:24:22 hi-fi-ms sudo[27457]: pam_unix(sudo:session): session closed for user root Mar 04 22:24:22 hi-fi-ms volumio[1065]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 04 22:24:23 hi-fi-ms volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 04 22:24:23 hi-fi-ms volumio[1065]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 04 22:24:23 hi-fi-ms volumio[1065]: info: Discovery: Getting this device information Mar 04 22:24:23 hi-fi-ms volumio[1065]: info: CoreCommandRouter::volumioGetState Mar 04 22:24:23 hi-fi-ms volumio[1065]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 04 22:24:30 hi-fi-ms volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 04 22:24:30 hi-fi-ms volumio[1065]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 04 22:24:30 hi-fi-ms volumio[1065]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 04 22:24:30 hi-fi-ms volumio[1065]: info: Received Get System Version Mar 04 22:24:30 hi-fi-ms volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 04 22:24:30 hi-fi-ms volumio[1065]: info: Received Get System Info Mar 04 22:24:30 hi-fi-ms volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 04 22:24:30 hi-fi-ms volumio[1065]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 04 22:24:30 hi-fi-ms volumio[1065]: info: Discovery: Getting this device information Mar 04 22:24:30 hi-fi-ms volumio[1065]: info: CoreCommandRouter::volumioGetState Mar 04 22:24:30 hi-fi-ms volumio[1065]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 04 22:24:37 hi-fi-ms volumio[1065]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 04 22:24:37 hi-fi-ms volumio[1065]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 04 22:24:37 hi-fi-ms volumio[1065]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 04 22:24:37 hi-fi-ms volumio[1065]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 04 22:24:37 hi-fi-ms volumio[1065]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Mar 04 22:24:37 hi-fi-ms volumio[1065]: info: Received Get System Version Mar 04 22:24:37 hi-fi-ms volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 04 22:24:45 hi-fi-ms volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 04 22:24:45 hi-fi-ms volumio[1065]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 04 22:24:45 hi-fi-ms volumio[1065]: info: Discovery: Getting this device information Mar 04 22:24:45 hi-fi-ms volumio[1065]: info: CoreCommandRouter::volumioGetState Mar 04 22:24:45 hi-fi-ms volumio[1065]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 04 22:24:53 hi-fi-ms volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 04 22:24:53 hi-fi-ms volumio[1065]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 04 22:24:53 hi-fi-ms volumio[1065]: info: Discovery: Getting this device information Mar 04 22:24:53 hi-fi-ms volumio[1065]: info: CoreCommandRouter::volumioGetState Mar 04 22:24:53 hi-fi-ms volumio[1065]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 04 22:24:58 hi-fi-ms volumio[1065]: info: CALLMETHOD: system_controller system saveGeneralSettings [object Object] Mar 04 22:24:58 hi-fi-ms volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Mar 04 22:24:58 hi-fi-ms volumio[1065]: info: System name has changed, restarting Shairport Sync Mar 04 22:24:58 hi-fi-ms volumio[1065]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 22:24:58 hi-fi-ms volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 22:24:58 hi-fi-ms volumio[1065]: No protocol specified Mar 04 22:24:58 hi-fi-ms volumio[1065]: xcb_connection_has_error() returned true Mar 04 22:24:58 hi-fi-ms volumio[1065]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 04 22:24:58 hi-fi-ms volumio[1065]: No protocol specified Mar 04 22:24:58 hi-fi-ms volumio[1065]: xcb_connection_has_error() returned true Mar 04 22:24:58 hi-fi-ms volumio[1065]: info: Updating MyVolumio device info Mar 04 22:24:58 hi-fi-ms volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 22:24:58 hi-fi-ms volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 22:24:58 hi-fi-ms volumio[1065]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 22:24:58 hi-fi-ms vtcs[27251]: [2025-03-04 22:24:58.052] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Mar 04 22:24:58 hi-fi-ms vtcs[27251]: [2025-03-04 22:24:58.052] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Mar 04 22:24:58 hi-fi-ms sudo[27575]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 04 22:24:58 hi-fi-ms sudo[27575]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 04 22:24:58 hi-fi-ms systemd[1]: Stopping Volumio Tidal Connect Service... Mar 04 22:24:58 hi-fi-ms volumio[1065]: info: Discovery: Restarting Advertising due to device name change Mar 04 22:24:58 hi-fi-ms systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Mar 04 22:24:58 hi-fi-ms systemd[1]: vtcs.service: Succeeded. Mar 04 22:24:58 hi-fi-ms systemd[1]: Stopped Volumio Tidal Connect Service. Mar 04 22:24:58 hi-fi-ms sudo[27575]: pam_unix(sudo:session): session closed for user root Mar 04 22:24:58 hi-fi-ms sudo[27578]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Mar 04 22:24:58 hi-fi-ms sudo[27578]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 04 22:24:58 hi-fi-ms sudo[27578]: pam_unix(sudo:session): session closed for user root Mar 04 22:24:58 hi-fi-ms sudo[27582]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name RodneyRoom Mar 04 22:24:58 hi-fi-ms sudo[27582]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 04 22:24:58 hi-fi-ms sudo[27582]: pam_unix(sudo:session): session closed for user root Mar 04 22:24:58 hi-fi-ms volumio[1065]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 04 22:24:58 hi-fi-ms sudo[27585]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 04 22:24:58 hi-fi-ms sudo[27585]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 04 22:24:58 hi-fi-ms volumio[1065]: error: Upnp client error: Error: This socket has been ended by the other party Mar 04 22:24:58 hi-fi-ms volumio[1065]: error: Cannot set new Bluetooth Name : Error: Command failed: /usr/bin/sudo /bin/hciconfig hci0 name 'RodneyRoom' Mar 04 22:24:58 hi-fi-ms volumio[1065]: Can't get device info: No such device Mar 04 22:24:58 hi-fi-ms sudo[27585]: pam_unix(sudo:session): session closed for user root Mar 04 22:24:58 hi-fi-ms volumio[1065]: info: Starting Shairport Sync Mar 04 22:24:58 hi-fi-ms sudo[27590]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hosts Mar 04 22:24:58 hi-fi-ms sudo[27590]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 04 22:24:58 hi-fi-ms sudo[27590]: pam_unix(sudo:session): session closed for user root Mar 04 22:24:58 hi-fi-ms volumio[1065]: info: Permissions for /etc/hosts set Mar 04 22:24:58 hi-fi-ms sudo[27593]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 04 22:24:58 hi-fi-ms sudo[27593]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 04 22:24:58 hi-fi-ms systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 04 22:24:58 hi-fi-ms volumio[1065]: info: Hostname now is rodneyroom Mar 04 22:24:58 hi-fi-ms systemd[1]: shairport-sync.service: Succeeded. Mar 04 22:24:58 hi-fi-ms systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 04 22:24:58 hi-fi-ms volumio[1065]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 04 22:24:58 hi-fi-ms volumio[1065]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 04 22:24:58 hi-fi-ms systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 04 22:24:58 hi-fi-ms sudo[27593]: pam_unix(sudo:session): session closed for user root Mar 04 22:24:58 hi-fi-ms volumio[1065]: info: Shairport-Sync Started Mar 04 22:24:58 hi-fi-ms sudo[27599]: volumio : unable to resolve host hi-fi-ms Mar 04 22:24:58 hi-fi-ms sudo[27599]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 04 22:24:58 hi-fi-ms sudo[27599]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /etc/avahi/services/ Mar 04 22:24:58 hi-fi-ms sudo[27599]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 04 22:24:58 hi-fi-ms sudo[27599]: pam_unix(sudo:session): session closed for user root Mar 04 22:24:58 hi-fi-ms sudo[27596]: volumio : unable to resolve host hi-fi-ms Mar 04 22:24:58 hi-fi-ms sudo[27596]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 04 22:24:58 hi-fi-ms volumio[1065]: info: Permissions for /etc/avahi/services/volumio.service Mar 04 22:24:58 hi-fi-ms avahi-daemon[762]: Files changed, reloading. Mar 04 22:24:58 hi-fi-ms avahi-daemon[762]: Service group file /services/volumio.service changed, reloading. Mar 04 22:24:58 hi-fi-ms volumio[1065]: info: Avahi name changed to rodneyroom Mar 04 22:24:58 hi-fi-ms sudo[27596]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hostname rodneyroom Mar 04 22:24:58 hi-fi-ms sudo[27596]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 04 22:24:58 rodneyroom sudo[27596]: pam_unix(sudo:session): session closed for user root Mar 04 22:24:58 rodneyroom volumio[1065]: info: New hostname set Mar 04 22:24:58 rodneyroom volumio[1065]: error: Failed to update MyVolumio device: {"message":"Unauthenticated"} Mar 04 22:24:59 rodneyroom volumio[1065]: info: Discovery: A device disappeared from network Mar 04 22:24:59 rodneyroom volumio[1065]: info: Discovery: Device hi-fi-ms disappeared from network Mar 04 22:24:59 rodneyroom avahi-daemon[762]: Service "RodneyRoom" (/services/volumio.service) successfully established. Mar 04 22:25:01 rodneyroom volumio[1065]: info: TidalConnect service stoped! Mar 04 22:25:01 rodneyroom volumio[1065]: info: TidalConnect service stoped! Mar 04 22:25:01 rodneyroom volumio[1065]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Mar 04 22:25:01 rodneyroom volumio[1065]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Mar 04 22:25:01 rodneyroom sudo[27614]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Mar 04 22:25:01 rodneyroom sudo[27614]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 04 22:25:01 rodneyroom systemd[1]: Started Volumio Tidal Connect Service. Mar 04 22:25:01 rodneyroom sudo[27614]: pam_unix(sudo:session): session closed for user root Mar 04 22:25:01 rodneyroom volumio[1065]: info: Executing endpoint tc_getconfig Mar 04 22:25:01 rodneyroom volumio[1065]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Mar 04 22:25:01 rodneyroom vtcs[27616]: STARTING TidalConnect services, version: 1.4.0.34 Mar 04 22:25:01 rodneyroom vtcs[27616]: STARTED TidalConnect services. Mar 04 22:25:01 rodneyroom volumio[1065]: info: Executing endpoint tc_connect Mar 04 22:25:01 rodneyroom volumio[1065]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Mar 04 22:25:01 rodneyroom volumio[1065]: info: Connecting to TidalConnect Mar 04 22:25:01 rodneyroom volumio[1065]: info: CoreCommandRouter::servicePushState Mar 04 22:25:01 rodneyroom volumio[1065]: info: CoreStateMachine::pushState Mar 04 22:25:01 rodneyroom volumio[1065]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 22:25:01 rodneyroom volumio[1065]: info: CoreCommandRouter::volumioPushState Mar 04 22:25:01 rodneyroom volumio[1065]: info: MRS: Pushing multiroomSync output update for this device Mar 04 22:25:01 rodneyroom volumio[1065]: info: MRS: Pushing multiroomSync output Mar 04 22:25:01 rodneyroom volumio[1065]: info: CorePlayQueue::getTrack 0 Mar 04 22:25:01 rodneyroom volumio[1065]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Mar 04 22:25:01 rodneyroom volumio[1065]: info: CoreCommandRouter::servicePushState Mar 04 22:25:01 rodneyroom volumio[1065]: info: CoreStateMachine::pushState Mar 04 22:25:01 rodneyroom volumio[1065]: info: CoreCommandRouter::volumioPushState Mar 04 22:25:01 rodneyroom volumio[1065]: info: MRS: Pushing multiroomSync output update for this device Mar 04 22:25:01 rodneyroom volumio[1065]: info: MRS: Pushing multiroomSync output Mar 04 22:25:01 rodneyroom volumio[1065]: info: CorePlayQueue::getTrack 0 Mar 04 22:25:01 rodneyroom volumio[1065]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Mar 04 22:25:01 rodneyroom volumio[1065]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.5DozcAQwKSZgdmarQnkBoA0k1gf2.8ca8e6b9b16c0c428481f848ff69c0d9.state.status' Mar 04 22:25:03 rodneyroom volumio[1065]: info: Discovery: Started advertising with name: RodneyRoom Mar 04 22:25:03 rodneyroom volumio[1065]: info: Executing endpoint metavolumio Mar 04 22:25:03 rodneyroom volumio[1065]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 04 22:25:03 rodneyroom volumio[1065]: info: Discovery: adding 843f4aa1-f9ec-4406-8d52-bfa141759b3b Mar 04 22:25:03 rodneyroom volumio[1065]: info: Discovery: Found device RodneyRoom Mar 04 22:25:03 rodneyroom volumio[1065]: info: CoreCommandRouter::volumioGetState Mar 04 22:25:03 rodneyroom volumio[1065]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 04 22:25:03 rodneyroom volumio[1065]: Error: The value NaN is not a number Mar 04 22:25:03 rodneyroom volumio[1065]: at Config.forceToType (/volumio/node_modules/v-conf/index.js:322:20) Mar 04 22:25:03 rodneyroom volumio[1065]: at Config.set (/volumio/node_modules/v-conf/index.js:153:25) Mar 04 22:25:03 rodneyroom volumio[1065]: at ControllerVolumioDiscovery.updateMultiroomDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:323:25) Mar 04 22:25:03 rodneyroom volumio[1065]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:273:10) Mar 04 22:25:03 rodneyroom volumio[1065]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12) Mar 04 22:25:03 rodneyroom volumio[1065]: at Browser.emit (events.js:400:28) Mar 04 22:25:03 rodneyroom volumio[1065]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) Mar 04 22:25:03 rodneyroom volumio[1065]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) Mar 04 22:25:03 rodneyroom volumio[1065]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5) Mar 04 22:25:03 rodneyroom volumio[1065]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21) Mar 04 22:25:03 rodneyroom volumio[1065]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 Mar 04 22:25:03 rodneyroom volumio[1065]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7) Mar 04 22:25:03 rodneyroom volumio[1065]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) Mar 04 22:25:03 rodneyroom volumio[1065]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 04 22:25:04 rodneyroom sudo[27693]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-04 22:24 Mar 04 22:25:04 rodneyroom sudo[27693]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 07:25:16 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="66c567362bdbb3dc1644a18f7879afe7"