Jun 20 10:09:02 volumio2 volumio[1419]: info: CoreCommandRouter::volumioGetState Jun 20 10:09:05 volumio2 volumio[1419]: info: Preload queue cleared Jun 20 10:09:05 volumio2 volumio[1419]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 20 10:09:05 volumio2 volumio[1419]: info: CoreStateMachine::ClearQueue Jun 20 10:09:05 volumio2 volumio[1419]: info: CoreStateMachine::stop Jun 20 10:09:05 volumio2 volumio[1419]: info: CoreStateMachine::serviceStop Jun 20 10:09:05 volumio2 volumio[1419]: info: Received STOP, but no service to execute it Jun 20 10:09:05 volumio2 volumio[1419]: info: CorePlayQueue::clearPlayQueue Jun 20 10:09:05 volumio2 volumio[1419]: info: CorePlayQueue::saveQueue Jun 20 10:09:05 volumio2 volumio[1419]: info: CoreCommandRouter::volumioPushQueue Jun 20 10:09:05 volumio2 volumio[1419]: info: CoreStateMachine::addQueueItems Jun 20 10:09:05 volumio2 volumio[1419]: info: CorePlayQueue::addQueueItems Jun 20 10:09:05 volumio2 volumio[1419]: info: Preload queue cleared Jun 20 10:09:05 volumio2 volumio[1419]: info: Adding Item to queue: tidal://song/96899507 Jun 20 10:09:05 volumio2 volumio[1419]: info: Using cached record of: tidal://song/96899507 Jun 20 10:09:05 volumio2 volumio[1419]: info: CoreCommandRouter::volumioPushQueue Jun 20 10:09:05 volumio2 volumio[1419]: info: CorePlayQueue::saveQueue Jun 20 10:09:05 volumio2 volumio[1419]: info: CoreStateMachine::updateTrackBlock Jun 20 10:09:05 volumio2 volumio[1419]: info: CorePlayQueue::getTrackBlock Jun 20 10:09:05 volumio2 volumio[1419]: info: CoreCommandRouter::volumioPlay Jun 20 10:09:05 volumio2 volumio[1419]: verbose: UNSET VOLATILE: Service: undefined Jun 20 10:09:05 volumio2 volumio[1419]: ------------------------------------ BT MESSAGE: [FUNC] detachBluetooth Jun 20 10:09:05 volumio2 volumio[1419]: ------------------------------------ BT MESSAGE: [FUNC] btAudioOutput Jun 20 10:09:05 volumio2 volumio[1419]: ------------------------------------ BT MESSAGE: [dbus-next] Disabling Bluetooth Audio Output Jun 20 10:09:05 volumio2 volumio[1419]: info: CoreStateMachine::play index 0 Jun 20 10:09:05 volumio2 volumio[1419]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 10:09:05 volumio2 volumio[1419]: info: CoreStateMachine::addQueueItems Jun 20 10:09:05 volumio2 volumio[1419]: info: CorePlayQueue::addQueueItems Jun 20 10:09:05 volumio2 volumio[1419]: info: Preload queue cleared Jun 20 10:09:05 volumio2 volumio[1419]: info: Adding Item to queue: tidal://song/381769495 Jun 20 10:09:05 volumio2 volumio[1419]: info: Using cached record of: tidal://song/381769495 Jun 20 10:09:05 volumio2 volumio[1419]: info: Adding Item to queue: tidal://song/65749630 Jun 20 10:09:05 volumio2 volumio[1419]: info: Using cached record of: tidal://song/65749630 Jun 20 10:09:05 volumio2 volumio[1419]: info: Adding Item to queue: tidal://song/103318518 Jun 20 10:09:05 volumio2 volumio[1419]: info: Using cached record of: tidal://song/103318518 Jun 20 10:09:05 volumio2 volumio[1419]: info: Adding Item to queue: tidal://song/241255366 Jun 20 10:09:05 volumio2 volumio[1419]: info: Using cached record of: tidal://song/241255366 Jun 20 10:09:05 volumio2 volumio[1419]: ------------------------------------ BT MESSAGE: Bluetooth audio output disabled. Jun 20 10:09:05 volumio2 volumio[1419]: info: CoreStateMachine::stop Jun 20 10:09:05 volumio2 volumio[1419]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 10:09:05 volumio2 volumio[1419]: info: CoreCommandRouter::volumioPushQueue Jun 20 10:09:05 volumio2 volumio[1419]: info: CorePlayQueue::saveQueue Jun 20 10:09:05 volumio2 volumio[1419]: info: CoreStateMachine::resetVolumioState Jun 20 10:09:05 volumio2 volumio[1419]: info: CoreStateMachine::getcurrentVolume Jun 20 10:09:05 volumio2 volumio[1419]: info: CoreCommandRouter::volumioRetrievevolume Jun 20 10:09:05 volumio2 volumio[1419]: info: CoreStateMachine::play index undefined Jun 20 10:09:05 volumio2 volumio[1419]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 10:09:05 volumio2 volumio[1419]: info: CoreStateMachine::updateTrackBlock Jun 20 10:09:05 volumio2 volumio[1419]: info: CorePlayQueue::getTrackBlock Jun 20 10:09:05 volumio2 volumio[1419]: info: CoreStateMachine::pushState Jun 20 10:09:05 volumio2 volumio[1419]: info: CorePlayQueue::getTrack 0 Jun 20 10:09:05 volumio2 volumio[1419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 20 10:09:05 volumio2 volumio[1419]: info: CoreCommandRouter::volumioPushState Jun 20 10:09:05 volumio2 volumio[1419]: info: MRS: Pushing multiroomSync output update for this device Jun 20 10:09:05 volumio2 volumio[1419]: info: MRS: Pushing multiroomSync output Jun 20 10:09:05 volumio2 volumio[1419]: info: CoreCommandRouter::volumioStop Jun 20 10:09:05 volumio2 volumio[1419]: info: CoreStateMachine::stop Jun 20 10:09:05 volumio2 volumio[1419]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 10:09:05 volumio2 volumio[1419]: info: CorePlayQueue::getTrack 0 Jun 20 10:09:05 volumio2 volumio[1419]: info: CoreStateMachine::startPlaybackTimer Jun 20 10:09:05 volumio2 volumio[1419]: info: CorePlayQueue::getTrack 0 Jun 20 10:09:05 volumio2 volumio[1419]: info: [1750406945365] ControllerTidal::clearAddPlayTrack Jun 20 10:09:05 volumio2 volumio[1419]: info: Getting stream with soundQuality LOSSLESS Jun 20 10:09:05 volumio2 volumio[1419]: info: FusionDsp - Volumio is not playing Jun 20 10:09:05 volumio2 volumio[1419]: info: FusionDsp - Clipped samples monitor stopped Jun 20 10:09:05 volumio2 volumio[1419]: info: getStreamUrl took 194 milliseconds Jun 20 10:09:05 volumio2 volumio[1419]: verbose: ControllerMpd::sendMpdCommand stop Jun 20 10:09:05 volumio2 volumio[1419]: info: sendMpdCommand stop took 1 milliseconds Jun 20 10:09:05 volumio2 volumio[1419]: verbose: ControllerMpd::sendMpdCommand clear Jun 20 10:09:05 volumio2 volumio[1419]: info: Jun 20 10:09:05 volumio2 volumio[1419]: ---------------------------- MPD announces system playlist update Jun 20 10:09:05 volumio2 volumio[1419]: info: Ignoring MPD Status Update Jun 20 10:09:05 volumio2 volumio[1419]: info: sendMpdCommand clear took 0 milliseconds Jun 20 10:09:05 volumio2 volumio[1419]: verbose: ControllerMpd::sendMpdCommand add "http://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKwgDEic3ODNmNWM1ZTFiMTE5Yzg0N2ZkNTZjNzAwZjVkOGFiM182MS5tcDQ/0.flac?token=1750410545~ZDY3ZDZjNDgyNjYzNGQ0ODBmYTIxOTBkYzA2NWE2NTVmMzNjM2ZlYw==" Jun 20 10:09:05 volumio2 volumio[1419]: error: updateQueue error: null Jun 20 10:09:05 volumio2 volumio[1419]: info: ------------------------------ 2ms Jun 20 10:09:05 volumio2 volumio[1419]: info: Jun 20 10:09:05 volumio2 volumio[1419]: ---------------------------- MPD announces system playlist update Jun 20 10:09:05 volumio2 volumio[1419]: info: Ignoring MPD Status Update Jun 20 10:09:05 volumio2 volumio[1419]: info: sendMpdCommand add "http://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKwgDEic3ODNmNWM1ZTFiMTE5Yzg0N2ZkNTZjNzAwZjVkOGFiM182MS5tcDQ/0.flac?token=1750410545~ZDY3ZDZjNDgyNjYzNGQ0ODBmYTIxOTBkYzA2NWE2NTVmMzNjM2ZlYw==" took 1 milliseconds Jun 20 10:09:05 volumio2 volumio[1419]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 20 10:09:05 volumio2 volumio[1419]: verbose: ControllerMpd::sendMpdCommand play Jun 20 10:09:05 volumio2 volumio[1419]: info: ------------------------------ 3ms Jun 20 10:09:05 volumio2 volumio[1419]: info: sendMpdCommand play took 2 milliseconds Jun 20 10:09:05 volumio2 volumio[1419]: info: Jun 20 10:09:05 volumio2 volumio[1419]: ---------------------------- MPD announces state update: player Jun 20 10:09:05 volumio2 volumio[1419]: info: ControllerMpd::getState Jun 20 10:09:05 volumio2 volumio[1419]: verbose: ControllerMpd::sendMpdCommand status Jun 20 10:09:05 volumio2 volumio[1419]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jun 20 10:09:05 volumio2 volumio[1419]: info: FusionDsp - ---- read samplerate from file: 44100 Jun 20 10:09:05 volumio2 volumio[1419]: info: FusionDsp - File size found in array! Jun 20 10:09:05 volumio2 volumio[1419]: info: Jun 20 10:09:05 volumio2 volumio[1419]: ---------------------------- MPD announces state update: player Jun 20 10:09:05 volumio2 volumio[1419]: info: sendMpdCommand status took 26 milliseconds Jun 20 10:09:05 volumio2 volumio[1419]: info: ControllerMpd::getState Jun 20 10:09:05 volumio2 volumio[1419]: verbose: ControllerMpd::sendMpdCommand status Jun 20 10:09:05 volumio2 volumio[1419]: verbose: ControllerMpd::parseState Jun 20 10:09:05 volumio2 volumio[1419]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 10:09:05 volumio2 volumio[1419]: info: sendMpdCommand status took 2 milliseconds Jun 20 10:09:05 volumio2 volumio[1419]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 20 10:09:05 volumio2 volumio[1419]: verbose: ControllerMpd::parseState Jun 20 10:09:05 volumio2 volumio[1419]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 10:09:05 volumio2 volumio[1419]: verbose: ControllerMpd::parseTrackInfo Jun 20 10:09:05 volumio2 volumio[1419]: info: ControllerMpd::pushState Jun 20 10:09:05 volumio2 volumio[1419]: info: CoreCommandRouter::servicePushState Jun 20 10:09:05 volumio2 volumio[1419]: info: CorePlayQueue::getTrack 0 Jun 20 10:09:05 volumio2 volumio[1419]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":345,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"559 Kbps","isStreaming":false,"title":"0.flac?token=1750410545~ZDY3ZDZjNDgyNjYzNGQ0ODBmYTIxOTBkYzA2NWE2NTVmMzNjM2ZlYw==","artist":null,"album":null,"uri":"http://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKwgDEic3ODNmNWM1ZTFiMTE5Yzg0N2ZkNTZjNzAwZjVkOGFiM182MS5tcDQ/0.flac?token=1750410545~ZDY3ZDZjNDgyNjYzNGQ0ODBmYTIxOTBkYzA2NWE2NTVmMzNjM2ZlYw==","trackType":"tidal"} Jun 20 10:09:05 volumio2 volumio[1419]: verbose: CURRENT POSITION 0 Jun 20 10:09:05 volumio2 volumio[1419]: info: CoreStateMachine::syncState stateService play Jun 20 10:09:05 volumio2 volumio[1419]: info: CoreStateMachine::syncState currentStatus stop Jun 20 10:09:05 volumio2 volumio[1419]: info: ------------------------------ 35ms Jun 20 10:09:05 volumio2 volumio[1419]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 20 10:09:05 volumio2 volumio[1419]: verbose: ControllerMpd::parseTrackInfo Jun 20 10:09:05 volumio2 volumio[1419]: info: ControllerMpd::pushState Jun 20 10:09:05 volumio2 volumio[1419]: info: CoreCommandRouter::servicePushState Jun 20 10:09:05 volumio2 volumio[1419]: info: CorePlayQueue::getTrack 0 Jun 20 10:09:05 volumio2 volumio[1419]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1209,"duration":345,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"579 Kbps","isStreaming":false,"title":"0.flac?token=1750410545~ZDY3ZDZjNDgyNjYzNGQ0ODBmYTIxOTBkYzA2NWE2NTVmMzNjM2ZlYw==","artist":null,"album":null,"uri":"http://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKwgDEic3ODNmNWM1ZTFiMTE5Yzg0N2ZkNTZjNzAwZjVkOGFiM182MS5tcDQ/0.flac?token=1750410545~ZDY3ZDZjNDgyNjYzNGQ0ODBmYTIxOTBkYzA2NWE2NTVmMzNjM2ZlYw==","trackType":"tidal"} Jun 20 10:09:05 volumio2 volumio[1419]: verbose: CURRENT POSITION 0 Jun 20 10:09:05 volumio2 volumio[1419]: info: CoreStateMachine::syncState stateService play Jun 20 10:09:05 volumio2 volumio[1419]: info: CoreStateMachine::syncState currentStatus play Jun 20 10:09:05 volumio2 volumio[1419]: info: Received an update from plugin. extracting info from payload Jun 20 10:09:05 volumio2 volumio[1419]: info: CoreStateMachine::pushState Jun 20 10:09:05 volumio2 volumio[1419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 20 10:09:05 volumio2 volumio[1419]: info: CoreCommandRouter::volumioPushState Jun 20 10:09:05 volumio2 volumio[1419]: info: MRS: Pushing multiroomSync output update for this device Jun 20 10:09:05 volumio2 volumio[1419]: info: MRS: Pushing multiroomSync output Jun 20 10:09:05 volumio2 volumio[1419]: info: CoreStateMachine::pushState Jun 20 10:09:05 volumio2 volumio[1419]: info: CoreCommandRouter::volumioPushState Jun 20 10:09:05 volumio2 volumio[1419]: info: MRS: Pushing multiroomSync output update for this device Jun 20 10:09:05 volumio2 volumio[1419]: info: MRS: Pushing multiroomSync output Jun 20 10:09:05 volumio2 volumio[1419]: info: ------------------------------ 44ms Jun 20 10:09:05 volumio2 volumio[1419]: info: FusionDsp - Volumio is playing Jun 20 10:09:05 volumio2 volumio[1419]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Jun 20 10:09:05 volumio2 volumio[1419]: info: FusionDsp - Volumio is playing Jun 20 10:09:05 volumio2 volumio[1419]: info: FusionDsp - Clipping Monitor started Jun 20 10:09:05 volumio2 volumio[1419]: info: FusionDsp - Clipping Monitor started Jun 20 10:09:07 volumio2 volumio[1419]: info: Executing endpoint metavolumio Jun 20 10:09:07 volumio2 volumio[1419]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 20 10:09:07 volumio2 volumio[1419]: info: Executing endpoint metavolumio Jun 20 10:09:07 volumio2 volumio[1419]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 20 10:09:07 volumio2 volumio[1419]: info: Executing endpoint metavolumio Jun 20 10:09:07 volumio2 volumio[1419]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 20 10:09:11 volumio2 volumio[1419]: error: Failed request for metavolumio API Jun 20 10:09:12 volumio2 volumio[1419]: info: CoreCommandRouter::volumioGetState Jun 20 10:09:12 volumio2 volumio[1419]: info: Listing playlists Jun 20 10:09:12 volumio2 volumio[1419]: info: Listing playlists Jun 20 10:09:17 volumio2 volumio[1419]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Jun 20 10:09:17 volumio2 volumio[1419]: info: browseTIDALUri took 278 milliseconds Jun 20 10:09:17 volumio2 volumio[1419]: info: Preload queue cleared Jun 20 10:09:17 volumio2 volumio[1419]: info: Preloading song: tidal://song/292053218 Jun 20 10:09:17 volumio2 volumio[1419]: info: Preloading song: tidal://song/79358622 Jun 20 10:09:17 volumio2 volumio[1419]: info: Preloading song: tidal://song/78498211 Jun 20 10:09:17 volumio2 volumio[1419]: info: Preloading song: tidal://song/94302676 Jun 20 10:09:17 volumio2 volumio[1419]: info: Preloading song: tidal://song/358115231 Jun 20 10:09:17 volumio2 volumio[1419]: info: Preloading song: tidal://song/316218407 Jun 20 10:09:17 volumio2 volumio[1419]: info: Preloading song: tidal://song/103317697 Jun 20 10:09:17 volumio2 volumio[1419]: info: Preloading song: tidal://song/16023303 Jun 20 10:09:17 volumio2 volumio[1419]: info: Preloading song: tidal://song/109899155 Jun 20 10:09:17 volumio2 volumio[1419]: info: Preloading song: tidal://song/111593618 Jun 20 10:09:17 volumio2 volumio[1419]: info: Preloading song: tidal://song/111593622 Jun 20 10:09:17 volumio2 volumio[1419]: info: Exploding uri tidal://song/292053218 in service tidal Jun 20 10:09:17 volumio2 volumio[1419]: info: Exploding uri tidal://song/79358622 in service tidal Jun 20 10:09:17 volumio2 volumio[1419]: info: Exploding uri tidal://song/78498211 in service tidal Jun 20 10:09:17 volumio2 volumio[1419]: info: explodeTIDALUri took 120 milliseconds Jun 20 10:09:17 volumio2 volumio[1419]: info: Exploding uri tidal://song/94302676 in service tidal Jun 20 10:09:17 volumio2 volumio[1419]: info: explodeTIDALUri took 145 milliseconds Jun 20 10:09:17 volumio2 volumio[1419]: info: Exploding uri tidal://song/358115231 in service tidal Jun 20 10:09:17 volumio2 volumio[1419]: info: explodeTIDALUri took 148 milliseconds Jun 20 10:09:17 volumio2 volumio[1419]: info: Exploding uri tidal://song/316218407 in service tidal Jun 20 10:09:17 volumio2 volumio[1419]: info: explodeTIDALUri took 116 milliseconds Jun 20 10:09:17 volumio2 volumio[1419]: info: Exploding uri tidal://song/103317697 in service tidal Jun 20 10:09:17 volumio2 volumio[1419]: info: explodeTIDALUri took 115 milliseconds Jun 20 10:09:18 volumio2 volumio[1419]: info: Exploding uri tidal://song/16023303 in service tidal Jun 20 10:09:18 volumio2 volumio[1419]: info: explodeTIDALUri took 120 milliseconds Jun 20 10:09:18 volumio2 volumio[1419]: info: Exploding uri tidal://song/109899155 in service tidal Jun 20 10:09:18 volumio2 volumio[1419]: info: explodeTIDALUri took 117 milliseconds Jun 20 10:09:18 volumio2 volumio[1419]: info: Exploding uri tidal://song/111593618 in service tidal Jun 20 10:09:18 volumio2 volumio[1419]: info: explodeTIDALUri took 117 milliseconds Jun 20 10:09:18 volumio2 volumio[1419]: info: Exploding uri tidal://song/111593622 in service tidal Jun 20 10:09:18 volumio2 volumio[1419]: info: explodeTIDALUri took 123 milliseconds Jun 20 10:09:18 volumio2 volumio[1419]: info: explodeTIDALUri took 116 milliseconds Jun 20 10:09:18 volumio2 volumio[1419]: info: explodeTIDALUri took 119 milliseconds Jun 20 10:09:20 volumio2 systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jun 20 10:09:20 volumio2 dbus-daemon[855]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.52' (uid=0 pid=3482 comm="timedatectl show --property=NTPSynchronized --valu") Jun 20 10:09:20 volumio2 systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jun 20 10:09:20 volumio2 dbus-daemon[855]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 20 10:09:20 volumio2 systemd[1]: Started systemd-timedated.service - Time & Date Service. Jun 20 10:09:20 volumio2 setdatetime-helper.sh[3481]: Time is already synchronized. Jun 20 10:09:20 volumio2 systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jun 20 10:09:20 volumio2 systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jun 20 10:09:22 volumio2 volumio[1419]: info: CoreCommandRouter::volumioGetState Jun 20 10:09:22 volumio2 volumio[1419]: info: Preload queue cleared Jun 20 10:09:22 volumio2 volumio[1419]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 20 10:09:22 volumio2 volumio[1419]: info: CoreStateMachine::ClearQueue Jun 20 10:09:22 volumio2 volumio[1419]: info: CoreStateMachine::stop Jun 20 10:09:22 volumio2 volumio[1419]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 10:09:22 volumio2 volumio[1419]: info: CoreStateMachine::stPlaybackTimer Jun 20 10:09:22 volumio2 volumio[1419]: info: CoreStateMachine::updateTrackBlock Jun 20 10:09:22 volumio2 volumio[1419]: info: CorePlayQueue::getTrackBlock Jun 20 10:09:22 volumio2 volumio[1419]: info: CoreStateMachine::pushState Jun 20 10:09:22 volumio2 volumio[1419]: info: CorePlayQueue::getTrack 0 Jun 20 10:09:22 volumio2 volumio[1419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 20 10:09:22 volumio2 volumio[1419]: info: CoreCommandRouter::volumioPushState Jun 20 10:09:22 volumio2 volumio[1419]: info: MRS: Pushing multiroomSync output update for this device Jun 20 10:09:22 volumio2 volumio[1419]: info: MRS: Pushing multiroomSync output Jun 20 10:09:22 volumio2 volumio[1419]: info: CoreStateMachine::serviceStop Jun 20 10:09:22 volumio2 volumio[1419]: info: CorePlayQueue::getTrack 0 Jun 20 10:09:22 volumio2 volumio[1419]: info: CoreCommandRouter::serviceStop Jun 20 10:09:22 volumio2 volumio[1419]: info: [1750406962410] ControllerTidal::stop Jun 20 10:09:22 volumio2 volumio[1419]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 20 10:09:22 volumio2 volumio[1419]: info: ControllerMpd::stop Jun 20 10:09:22 volumio2 volumio[1419]: verbose: ControllerMpd::sendMpdCommand stop Jun 20 10:09:22 volumio2 volumio[1419]: info: CorePlayQueue::clearPlayQueue Jun 20 10:09:22 volumio2 volumio[1419]: info: CorePlayQueue::saveQueue Jun 20 10:09:22 volumio2 volumio[1419]: info: CoreCommandRouter::volumioPushQueue Jun 20 10:09:22 volumio2 volumio[1419]: info: CoreStateMachine::addQueueItems Jun 20 10:09:22 volumio2 volumio[1419]: info: CorePlayQueue::addQueueItems Jun 20 10:09:22 volumio2 volumio[1419]: info: Preload queue cleared Jun 20 10:09:22 volumio2 volumio[1419]: info: Adding Item to queue: tidal://song/292053218 Jun 20 10:09:22 volumio2 volumio[1419]: info: Using cached record of: tidal://song/292053218 Jun 20 10:09:22 volumio2 volumio[1419]: info: Adding Item to queue: tidal://song/79358622 Jun 20 10:09:22 volumio2 volumio[1419]: info: Using cached record of: tidal://song/79358622 Jun 20 10:09:22 volumio2 volumio[1419]: info: Adding Item to queue: tidal://song/78498211 Jun 20 10:09:22 volumio2 volumio[1419]: info: Using cached record of: tidal://song/78498211 Jun 20 10:09:22 volumio2 volumio[1419]: info: Adding Item to queue: tidal://song/94302676 Jun 20 10:09:22 volumio2 volumio[1419]: info: Using cached record of: tidal://song/94302676 Jun 20 10:09:22 volumio2 volumio[1419]: info: Adding Item to queue: tidal://song/358115231 Jun 20 10:09:22 volumio2 volumio[1419]: info: Using cached record of: tidal://song/358115231 Jun 20 10:09:22 volumio2 volumio[1419]: info: Adding Item to queue: tidal://song/316218407 Jun 20 10:09:22 volumio2 volumio[1419]: info: Using cached record of: tidal://song/316218407 Jun 20 10:09:22 volumio2 volumio[1419]: info: Adding Item to queue: tidal://song/103317697 Jun 20 10:09:22 volumio2 volumio[1419]: info: Using cached record of: tidal://song/103317697 Jun 20 10:09:22 volumio2 volumio[1419]: info: Adding Item to queue: tidal://song/16023303 Jun 20 10:09:22 volumio2 volumio[1419]: info: Using cached record of: tidal://song/16023303 Jun 20 10:09:22 volumio2 volumio[1419]: info: Adding Item to queue: tidal://song/109899155 Jun 20 10:09:22 volumio2 volumio[1419]: info: Using cached record of: tidal://song/109899155 Jun 20 10:09:22 volumio2 volumio[1419]: info: CoreCommandRouter::volumioPushQueue Jun 20 10:09:22 volumio2 volumio[1419]: info: CorePlayQueue::saveQueue Jun 20 10:09:22 volumio2 volumio[1419]: info: CoreStateMachine::updateTrackBlock Jun 20 10:09:22 volumio2 volumio[1419]: info: CorePlayQueue::getTrackBlock Jun 20 10:09:22 volumio2 volumio[1419]: info: CoreCommandRouter::volumioPlay Jun 20 10:09:22 volumio2 volumio[1419]: info: CoreStateMachine::play index 8 Jun 20 10:09:22 volumio2 volumio[1419]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 10:09:22 volumio2 volumio[1419]: info: CoreStateMachine::addQueueItems Jun 20 10:09:22 volumio2 volumio[1419]: info: CorePlayQueue::addQueueItems Jun 20 10:09:22 volumio2 volumio[1419]: info: Preload queue cleared Jun 20 10:09:22 volumio2 volumio[1419]: info: Adding Item to queue: tidal://song/111593618 Jun 20 10:09:22 volumio2 volumio[1419]: info: Using cached record of: tidal://song/111593618 Jun 20 10:09:22 volumio2 volumio[1419]: info: Adding Item to queue: tidal://song/111593622 Jun 20 10:09:22 volumio2 volumio[1419]: info: Using cached record of: tidal://song/111593622 Jun 20 10:09:22 volumio2 volumio[1419]: info: CoreStateMachine::stop Jun 20 10:09:22 volumio2 volumio[1419]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 10:09:22 volumio2 volumio[1419]: info: CoreCommandRouter::volumioPushQueue Jun 20 10:09:22 volumio2 volumio[1419]: info: CorePlayQueue::saveQueue Jun 20 10:09:22 volumio2 volumio[1419]: info: CoreStateMachine::play index undefined Jun 20 10:09:22 volumio2 volumio[1419]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 10:09:22 volumio2 volumio[1419]: info: CoreStateMachine::updateTrackBlock Jun 20 10:09:22 volumio2 volumio[1419]: info: CorePlayQueue::getTrackBlock Jun 20 10:09:22 volumio2 volumio[1419]: info: CorePlayQueue::getTrack 8 Jun 20 10:09:22 volumio2 volumio[1419]: info: CoreStateMachine::startPlaybackTimer Jun 20 10:09:22 volumio2 volumio[1419]: info: CorePlayQueue::getTrack 8 Jun 20 10:09:22 volumio2 volumio[1419]: info: [1750406962440] ControllerTidal::clearAddPlayTrack Jun 20 10:09:22 volumio2 volumio[1419]: info: Getting stream with soundQuality LOSSLESS Jun 20 10:09:22 volumio2 volumio[1419]: info: FusionDsp - Volumio is not playing Jun 20 10:09:22 volumio2 volumio[1419]: info: FusionDsp - Clipped samples monitor stopped Jun 20 10:09:22 volumio2 volumio[1419]: info: Jun 20 10:09:22 volumio2 volumio[1419]: ---------------------------- MPD announces state update: player Jun 20 10:09:22 volumio2 volumio[1419]: info: sendMpdCommand stop took 46 milliseconds Jun 20 10:09:22 volumio2 volumio[1419]: info: ControllerMpd::getState Jun 20 10:09:22 volumio2 volumio[1419]: verbose: ControllerMpd::sendMpdCommand status Jun 20 10:09:22 volumio2 volumio[1419]: info: sendMpdCommand status took 1 milliseconds Jun 20 10:09:22 volumio2 volumio[1419]: verbose: ControllerMpd::parseState Jun 20 10:09:22 volumio2 volumio[1419]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 10:09:22 volumio2 volumio[1419]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 20 10:09:22 volumio2 volumio[1419]: verbose: ControllerMpd::parseTrackInfo Jun 20 10:09:22 volumio2 volumio[1419]: info: ControllerMpd::pushState Jun 20 10:09:22 volumio2 volumio[1419]: info: CoreCommandRouter::servicePushState Jun 20 10:09:22 volumio2 volumio[1419]: info: CoreStateMachine::pushState Jun 20 10:09:22 volumio2 volumio[1419]: info: CorePlayQueue::getTrack 8 Jun 20 10:09:22 volumio2 volumio[1419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 20 10:09:22 volumio2 volumio[1419]: info: CoreCommandRouter::volumioPushState Jun 20 10:09:22 volumio2 volumio[1419]: info: MRS: Pushing multiroomSync output update for this device Jun 20 10:09:22 volumio2 volumio[1419]: info: MRS: Pushing multiroomSync output Jun 20 10:09:22 volumio2 volumio[1419]: info: CorePlayQueue::getTrack 8 Jun 20 10:09:22 volumio2 volumio[1419]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Jun 20 10:09:22 volumio2 volumio[1419]: info: ------------------------------ 14ms Jun 20 10:09:22 volumio2 volumio[1419]: info: FusionDsp - Volumio is not playing Jun 20 10:09:22 volumio2 volumio[1419]: info: FusionDsp - Clipped samples monitor stopped Jun 20 10:09:22 volumio2 volumio[1419]: info: getStreamUrl took 148 milliseconds Jun 20 10:09:22 volumio2 volumio[1419]: verbose: ControllerMpd::sendMpdCommand stop Jun 20 10:09:22 volumio2 volumio[1419]: info: sendMpdCommand stop took 0 milliseconds Jun 20 10:09:22 volumio2 volumio[1419]: verbose: ControllerMpd::sendMpdCommand clear Jun 20 10:09:22 volumio2 volumio[1419]: info: Jun 20 10:09:22 volumio2 volumio[1419]: ---------------------------- MPD announces system playlist update Jun 20 10:09:22 volumio2 volumio[1419]: info: Ignoring MPD Status Update Jun 20 10:09:22 volumio2 volumio[1419]: info: sendMpdCommand clear took 1 milliseconds Jun 20 10:09:22 volumio2 volumio[1419]: verbose: ControllerMpd::sendMpdCommand add "http://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKwgDEidhYTY2MWI2NTgyNDJmNTc5YjdmZTEyYzZiYjIzMWVhMV82MS5tcDQ/0.flac?token=1750410562~NGUyYWU5YmU4MTlkYzhlYThiMDQzY2NlNjA5YmI3NWZiZWZkYmUyZA==" Jun 20 10:09:22 volumio2 volumio[1419]: error: updateQueue error: null Jun 20 10:09:22 volumio2 volumio[1419]: info: ------------------------------ 0ms Jun 20 10:09:22 volumio2 volumio[1419]: info: Jun 20 10:09:22 volumio2 volumio[1419]: ---------------------------- MPD announces system playlist update Jun 20 10:09:22 volumio2 volumio[1419]: info: Ignoring MPD Status Update Jun 20 10:09:22 volumio2 volumio[1419]: info: sendMpdCommand add "http://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKwgDEidhYTY2MWI2NTgyNDJmNTc5YjdmZTEyYzZiYjIzMWVhMV82MS5tcDQ/0.flac?token=1750410562~NGUyYWU5YmU4MTlkYzhlYThiMDQzY2NlNjA5YmI3NWZiZWZkYmUyZA==" took 1 milliseconds Jun 20 10:09:22 volumio2 volumio[1419]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 20 10:09:22 volumio2 volumio[1419]: verbose: ControllerMpd::sendMpdCommand play Jun 20 10:09:22 volumio2 volumio[1419]: info: ------------------------------ 0ms Jun 20 10:09:22 volumio2 volumio[1419]: info: sendMpdCommand play took 1 milliseconds Jun 20 10:09:22 volumio2 volumio[1419]: info: Jun 20 10:09:22 volumio2 volumio[1419]: ---------------------------- MPD announces state update: player Jun 20 10:09:22 volumio2 volumio[1419]: info: ControllerMpd::getState Jun 20 10:09:22 volumio2 volumio[1419]: verbose: ControllerMpd::sendMpdCommand status Jun 20 10:09:22 volumio2 volumio[1419]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jun 20 10:09:22 volumio2 volumio[1419]: info: FusionDsp - ---- read samplerate from file: 44100 Jun 20 10:09:22 volumio2 volumio[1419]: info: FusionDsp - File size found in array! Jun 20 10:09:22 volumio2 volumio[1419]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jun 20 10:09:22 volumio2 volumio[1419]: info: FusionDsp - ---- read samplerate from file: 44100 Jun 20 10:09:22 volumio2 volumio[1419]: info: FusionDsp - File size found in array! Jun 20 10:09:22 volumio2 volumio[1419]: info: Jun 20 10:09:22 volumio2 volumio[1419]: ---------------------------- MPD announces state update: player Jun 20 10:09:22 volumio2 volumio[1419]: info: sendMpdCommand status took 21 milliseconds Jun 20 10:09:22 volumio2 volumio[1419]: info: ControllerMpd::getState Jun 20 10:09:22 volumio2 volumio[1419]: verbose: ControllerMpd::sendMpdCommand status Jun 20 10:09:22 volumio2 volumio[1419]: verbose: ControllerMpd::parseState Jun 20 10:09:22 volumio2 volumio[1419]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 10:09:22 volumio2 volumio[1419]: info: sendMpdCommand status took 1 milliseconds Jun 20 10:09:22 volumio2 volumio[1419]: verbose: ControllerMpd::parseState Jun 20 10:09:22 volumio2 volumio[1419]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 10:09:22 volumio2 volumio[1419]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 20 10:09:22 volumio2 volumio[1419]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 20 10:09:22 volumio2 volumio[1419]: verbose: ControllerMpd::parseTrackInfo Jun 20 10:09:22 volumio2 volumio[1419]: verbose: ControllerMpd::parseTrackInfo Jun 20 10:09:22 volumio2 volumio[1419]: info: ControllerMpd::pushState Jun 20 10:09:22 volumio2 volumio[1419]: info: CoreCommandRouter::servicePushState Jun 20 10:09:22 volumio2 volumio[1419]: info: CorePlayQueue::getTrack 8 Jun 20 10:09:22 volumio2 volumio[1419]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":287,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?token=1750410562~NGUyYWU5YmU4MTlkYzhlYThiMDQzY2NlNjA5YmI3NWZiZWZkYmUyZA==","artist":null,"album":null,"uri":"http://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKwgDEidhYTY2MWI2NTgyNDJmNTc5YjdmZTEyYzZiYjIzMWVhMV82MS5tcDQ/0.flac?token=1750410562~NGUyYWU5YmU4MTlkYzhlYThiMDQzY2NlNjA5YmI3NWZiZWZkYmUyZA==","trackType":"tidal"} Jun 20 10:09:22 volumio2 volumio[1419]: verbose: CURRENT POSITION 8 Jun 20 10:09:22 volumio2 volumio[1419]: info: CoreStateMachine::syncState stateService play Jun 20 10:09:22 volumio2 volumio[1419]: info: CoreStateMachine::syncState currentStatus stop Jun 20 10:09:22 volumio2 volumio[1419]: info: ControllerMpd::pushState Jun 20 10:09:22 volumio2 volumio[1419]: info: CoreCommandRouter::servicePushState Jun 20 10:09:22 volumio2 volumio[1419]: info: CorePlayQueue::getTrack 8 Jun 20 10:09:22 volumio2 volumio[1419]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1209,"duration":287,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"369 Kbps","isStreaming":false,"title":"0.flac?token=1750410562~NGUyYWU5YmU4MTlkYzhlYThiMDQzY2NlNjA5YmI3NWZiZWZkYmUyZA==","artist":null,"album":null,"uri":"http://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKwgDEidhYTY2MWI2NTgyNDJmNTc5YjdmZTEyYzZiYjIzMWVhMV82MS5tcDQ/0.flac?token=1750410562~NGUyYWU5YmU4MTlkYzhlYThiMDQzY2NlNjA5YmI3NWZiZWZkYmUyZA==","trackType":"tidal"} Jun 20 10:09:22 volumio2 volumio[1419]: verbose: CURRENT POSITION 8 Jun 20 10:09:22 volumio2 volumio[1419]: info: CoreStateMachine::syncState stateService play Jun 20 10:09:22 volumio2 volumio[1419]: info: CoreStateMachine::syncState currentStatus play Jun 20 10:09:22 volumio2 volumio[1419]: info: Received an update from plugin. extracting info from payload Jun 20 10:09:22 volumio2 volumio[1419]: info: CoreStateMachine::pushState Jun 20 10:09:22 volumio2 volumio[1419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 20 10:09:22 volumio2 volumio[1419]: info: CoreCommandRouter::volumioPushState Jun 20 10:09:22 volumio2 volumio[1419]: info: MRS: Pushing multiroomSync output update for this device Jun 20 10:09:22 volumio2 volumio[1419]: info: MRS: Pushing multiroomSync output Jun 20 10:09:22 volumio2 volumio[1419]: info: CoreStateMachine::pushState Jun 20 10:09:22 volumio2 volumio[1419]: info: CoreCommandRouter::volumioPushState Jun 20 10:09:22 volumio2 volumio[1419]: info: MRS: Pushing multiroomSync output update for this device Jun 20 10:09:22 volumio2 volumio[1419]: info: MRS: Pushing multiroomSync output Jun 20 10:09:22 volumio2 volumio[1419]: info: ------------------------------ 38ms Jun 20 10:09:22 volumio2 volumio[1419]: info: ------------------------------ 32ms Jun 20 10:09:22 volumio2 volumio[1419]: info: FusionDsp - Volumio is playing Jun 20 10:09:22 volumio2 volumio[1419]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Jun 20 10:09:22 volumio2 volumio[1419]: info: FusionDsp - Volumio is playing Jun 20 10:09:22 volumio2 volumio[1419]: info: FusionDsp - Clipping Monitor started Jun 20 10:09:22 volumio2 volumio[1419]: info: FusionDsp - Clipping Monitor started Jun 20 10:09:24 volumio2 volumio[1419]: info: Executing endpoint metavolumio Jun 20 10:09:24 volumio2 volumio[1419]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 20 10:09:24 volumio2 volumio[1419]: info: Executing endpoint metavolumio Jun 20 10:09:24 volumio2 volumio[1419]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 20 10:09:24 volumio2 volumio[1419]: info: Executing endpoint metavolumio Jun 20 10:09:24 volumio2 volumio[1419]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 20 10:09:25 volumio2 volumio[1419]: error: Failed request for metavolumio API Jun 20 10:09:26 volumio2 volumio[1419]: info: Preload queue cleared Jun 20 10:09:26 volumio2 volumio[1419]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 20 10:09:26 volumio2 volumio[1419]: info: CoreStateMachine::ClearQueue Jun 20 10:09:26 volumio2 volumio[1419]: info: CoreStateMachine::stop Jun 20 10:09:26 volumio2 volumio[1419]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 10:09:26 volumio2 volumio[1419]: info: CoreStateMachine::stPlaybackTimer Jun 20 10:09:26 volumio2 volumio[1419]: info: CoreStateMachine::updateTrackBlock Jun 20 10:09:26 volumio2 volumio[1419]: info: CorePlayQueue::getTrackBlock Jun 20 10:09:26 volumio2 volumio[1419]: info: CoreStateMachine::pushState Jun 20 10:09:26 volumio2 volumio[1419]: info: CorePlayQueue::getTrack 8 Jun 20 10:09:26 volumio2 volumio[1419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 20 10:09:26 volumio2 volumio[1419]: info: CoreCommandRouter::volumioPushState Jun 20 10:09:26 volumio2 volumio[1419]: info: MRS: Pushing multiroomSync output update for this device Jun 20 10:09:26 volumio2 volumio[1419]: info: MRS: Pushing multiroomSync output Jun 20 10:09:26 volumio2 volumio[1419]: info: CoreStateMachine::serviceStop Jun 20 10:09:26 volumio2 volumio[1419]: info: CorePlayQueue::getTrack 8 Jun 20 10:09:26 volumio2 volumio[1419]: info: CoreCommandRouter::serviceStop Jun 20 10:09:26 volumio2 volumio[1419]: info: [1750406966685] ControllerTidal::stop Jun 20 10:09:26 volumio2 volumio[1419]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 20 10:09:26 volumio2 volumio[1419]: info: ControllerMpd::stop Jun 20 10:09:26 volumio2 volumio[1419]: verbose: ControllerMpd::sendMpdCommand stop Jun 20 10:09:26 volumio2 volumio[1419]: info: CorePlayQueue::clearPlayQueue Jun 20 10:09:26 volumio2 volumio[1419]: info: CorePlayQueue::saveQueue Jun 20 10:09:26 volumio2 volumio[1419]: info: CoreCommandRouter::volumioPushQueue Jun 20 10:09:26 volumio2 volumio[1419]: info: CoreStateMachine::addQueueItems Jun 20 10:09:26 volumio2 volumio[1419]: info: CorePlayQueue::addQueueItems Jun 20 10:09:26 volumio2 volumio[1419]: info: Preload queue cleared Jun 20 10:09:26 volumio2 volumio[1419]: info: Adding Item to queue: tidal://song/292053218 Jun 20 10:09:26 volumio2 volumio[1419]: info: Using cached record of: tidal://song/292053218 Jun 20 10:09:26 volumio2 volumio[1419]: info: Adding Item to queue: tidal://song/79358622 Jun 20 10:09:26 volumio2 volumio[1419]: info: Using cached record of: tidal://song/79358622 Jun 20 10:09:26 volumio2 volumio[1419]: info: Adding Item to queue: tidal://song/78498211 Jun 20 10:09:26 volumio2 volumio[1419]: info: Using cached record of: tidal://song/78498211 Jun 20 10:09:26 volumio2 volumio[1419]: info: Adding Item to queue: tidal://song/94302676 Jun 20 10:09:26 volumio2 volumio[1419]: info: Using cached record of: tidal://song/94302676 Jun 20 10:09:26 volumio2 volumio[1419]: info: Adding Item to queue: tidal://song/358115231 Jun 20 10:09:26 volumio2 volumio[1419]: info: Using cached record of: tidal://song/358115231 Jun 20 10:09:26 volumio2 volumio[1419]: info: Adding Item to queue: tidal://song/316218407 Jun 20 10:09:26 volumio2 volumio[1419]: info: Using cached record of: tidal://song/316218407 Jun 20 10:09:26 volumio2 volumio[1419]: info: CoreCommandRouter::volumioPushQueue Jun 20 10:09:26 volumio2 volumio[1419]: info: CorePlayQueue::saveQueue Jun 20 10:09:26 volumio2 volumio[1419]: info: CoreStateMachine::updateTrackBlock Jun 20 10:09:26 volumio2 volumio[1419]: info: CorePlayQueue::getTrackBlock Jun 20 10:09:26 volumio2 volumio[1419]: info: CoreCommandRouter::volumioPlay Jun 20 10:09:26 volumio2 volumio[1419]: info: CoreStateMachine::play index 5 Jun 20 10:09:26 volumio2 volumio[1419]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 10:09:26 volumio2 volumio[1419]: info: CoreStateMachine::addQueueItems Jun 20 10:09:26 volumio2 volumio[1419]: info: CorePlayQueue::addQueueItems Jun 20 10:09:26 volumio2 volumio[1419]: info: Preload queue cleared Jun 20 10:09:26 volumio2 volumio[1419]: info: Adding Item to queue: tidal://song/103317697 Jun 20 10:09:26 volumio2 volumio[1419]: info: Using cached record of: tidal://song/103317697 Jun 20 10:09:26 volumio2 volumio[1419]: info: Adding Item to queue: tidal://song/16023303 Jun 20 10:09:26 volumio2 volumio[1419]: info: Using cached record of: tidal://song/16023303 Jun 20 10:09:26 volumio2 volumio[1419]: info: Adding Item to queue: tidal://song/109899155 Jun 20 10:09:26 volumio2 volumio[1419]: info: Using cached record of: tidal://song/109899155 Jun 20 10:09:26 volumio2 volumio[1419]: info: Adding Item to queue: tidal://song/111593618 Jun 20 10:09:26 volumio2 volumio[1419]: info: Using cached record of: tidal://song/111593618 Jun 20 10:09:26 volumio2 volumio[1419]: info: Adding Item to queue: tidal://song/111593622 Jun 20 10:09:26 volumio2 volumio[1419]: info: Using cached record of: tidal://song/111593622 Jun 20 10:09:26 volumio2 volumio[1419]: info: CoreStateMachine::stop Jun 20 10:09:26 volumio2 volumio[1419]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 10:09:26 volumio2 volumio[1419]: info: CoreCommandRouter::volumioPushQueue Jun 20 10:09:26 volumio2 volumio[1419]: info: CorePlayQueue::saveQueue Jun 20 10:09:26 volumio2 volumio[1419]: info: CoreStateMachine::play index undefined Jun 20 10:09:26 volumio2 volumio[1419]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 10:09:26 volumio2 volumio[1419]: info: CoreStateMachine::updateTrackBlock Jun 20 10:09:26 volumio2 volumio[1419]: info: CorePlayQueue::getTrackBlock Jun 20 10:09:26 volumio2 volumio[1419]: info: CorePlayQueue::getTrack 5 Jun 20 10:09:26 volumio2 volumio[1419]: info: CoreStateMachine::startPlaybackTimer Jun 20 10:09:26 volumio2 volumio[1419]: info: CorePlayQueue::getTrack 5 Jun 20 10:09:26 volumio2 volumio[1419]: info: [1750406966711] ControllerTidal::clearAddPlayTrack Jun 20 10:09:26 volumio2 volumio[1419]: info: Getting stream with soundQuality LOSSLESS Jun 20 10:09:26 volumio2 volumio[1419]: info: FusionDsp - Volumio is not playing Jun 20 10:09:26 volumio2 volumio[1419]: info: FusionDsp - Clipped samples monitor stopped Jun 20 10:09:26 volumio2 volumio[1419]: info: Jun 20 10:09:26 volumio2 volumio[1419]: ---------------------------- MPD announces state update: player Jun 20 10:09:26 volumio2 volumio[1419]: info: sendMpdCommand stop took 39 milliseconds Jun 20 10:09:26 volumio2 volumio[1419]: info: ControllerMpd::getState Jun 20 10:09:26 volumio2 volumio[1419]: verbose: ControllerMpd::sendMpdCommand status Jun 20 10:09:26 volumio2 volumio[1419]: info: sendMpdCommand status took 1 milliseconds Jun 20 10:09:26 volumio2 volumio[1419]: verbose: ControllerMpd::parseState Jun 20 10:09:26 volumio2 volumio[1419]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 10:09:26 volumio2 volumio[1419]: info: sendMpdCommand playlistinfo took 0 milliseconds Jun 20 10:09:26 volumio2 volumio[1419]: verbose: ControllerMpd::parseTrackInfo Jun 20 10:09:26 volumio2 volumio[1419]: info: ControllerMpd::pushState Jun 20 10:09:26 volumio2 volumio[1419]: info: CoreCommandRouter::servicePushState Jun 20 10:09:26 volumio2 volumio[1419]: info: CoreStateMachine::pushState Jun 20 10:09:26 volumio2 volumio[1419]: info: CorePlayQueue::getTrack 5 Jun 20 10:09:26 volumio2 volumio[1419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 20 10:09:26 volumio2 volumio[1419]: info: CoreCommandRouter::volumioPushState Jun 20 10:09:26 volumio2 volumio[1419]: info: MRS: Pushing multiroomSync output update for this device Jun 20 10:09:26 volumio2 volumio[1419]: info: MRS: Pushing multiroomSync output Jun 20 10:09:26 volumio2 volumio[1419]: info: CorePlayQueue::getTrack 5 Jun 20 10:09:26 volumio2 volumio[1419]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Jun 20 10:09:26 volumio2 volumio[1419]: info: ------------------------------ 12ms Jun 20 10:09:26 volumio2 volumio[1419]: info: FusionDsp - Volumio is not playing Jun 20 10:09:26 volumio2 volumio[1419]: info: FusionDsp - Clipped samples monitor stopped Jun 20 10:09:26 volumio2 volumio[1419]: info: getStreamUrl took 148 milliseconds Jun 20 10:09:26 volumio2 volumio[1419]: verbose: ControllerMpd::sendMpdCommand stop Jun 20 10:09:26 volumio2 volumio[1419]: info: sendMpdCommand stop took 0 milliseconds Jun 20 10:09:26 volumio2 volumio[1419]: verbose: ControllerMpd::sendMpdCommand clear Jun 20 10:09:26 volumio2 volumio[1419]: info: Jun 20 10:09:26 volumio2 volumio[1419]: ---------------------------- MPD announces system playlist update Jun 20 10:09:26 volumio2 volumio[1419]: info: Ignoring MPD Status Update Jun 20 10:09:26 volumio2 volumio[1419]: info: sendMpdCommand clear took 0 milliseconds Jun 20 10:09:26 volumio2 volumio[1419]: verbose: ControllerMpd::sendMpdCommand add "http://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKwgDEidjMjZkYTNiN2Q0ZWQ3YzJjNTJmN2ZlNjg2MWEyMDFkZF82MS5tcDQ/0.flac?token=1750410566~NGJhZDZhY2EzNDcwY2RiMzI1Y2Q1NDMzOTEzNmE0ZWZhNzBkMWQ0YQ==" Jun 20 10:09:26 volumio2 volumio[1419]: error: updateQueue error: null Jun 20 10:09:26 volumio2 volumio[1419]: info: ------------------------------ 0ms Jun 20 10:09:26 volumio2 volumio[1419]: info: Jun 20 10:09:26 volumio2 volumio[1419]: ---------------------------- MPD announces system playlist update Jun 20 10:09:26 volumio2 volumio[1419]: info: Ignoring MPD Status Update Jun 20 10:09:26 volumio2 volumio[1419]: info: sendMpdCommand add "http://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKwgDEidjMjZkYTNiN2Q0ZWQ3YzJjNTJmN2ZlNjg2MWEyMDFkZF82MS5tcDQ/0.flac?token=1750410566~NGJhZDZhY2EzNDcwY2RiMzI1Y2Q1NDMzOTEzNmE0ZWZhNzBkMWQ0YQ==" took 1 milliseconds Jun 20 10:09:26 volumio2 volumio[1419]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 20 10:09:26 volumio2 volumio[1419]: verbose: ControllerMpd::sendMpdCommand play Jun 20 10:09:26 volumio2 volumio[1419]: info: ------------------------------ 0ms Jun 20 10:09:26 volumio2 volumio[1419]: info: sendMpdCommand play took 0 milliseconds Jun 20 10:09:26 volumio2 volumio[1419]: info: Jun 20 10:09:26 volumio2 volumio[1419]: ---------------------------- MPD announces state update: player Jun 20 10:09:26 volumio2 volumio[1419]: info: ControllerMpd::getState Jun 20 10:09:26 volumio2 volumio[1419]: verbose: ControllerMpd::sendMpdCommand status Jun 20 10:09:26 volumio2 volumio[1419]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jun 20 10:09:26 volumio2 volumio[1419]: info: FusionDsp - ---- read samplerate from file: 44100 Jun 20 10:09:26 volumio2 volumio[1419]: info: FusionDsp - File size found in array! Jun 20 10:09:26 volumio2 volumio[1419]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jun 20 10:09:26 volumio2 volumio[1419]: info: FusionDsp - ---- read samplerate from file: 44100 Jun 20 10:09:26 volumio2 volumio[1419]: info: FusionDsp - File size found in array! Jun 20 10:09:26 volumio2 volumio[1419]: info: Jun 20 10:09:26 volumio2 volumio[1419]: ---------------------------- MPD announces state update: player Jun 20 10:09:26 volumio2 volumio[1419]: info: sendMpdCommand status took 22 milliseconds Jun 20 10:09:26 volumio2 volumio[1419]: info: ControllerMpd::getState Jun 20 10:09:26 volumio2 volumio[1419]: verbose: ControllerMpd::sendMpdCommand status Jun 20 10:09:26 volumio2 volumio[1419]: verbose: ControllerMpd::parseState Jun 20 10:09:26 volumio2 volumio[1419]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 10:09:26 volumio2 volumio[1419]: info: sendMpdCommand status took 3 milliseconds Jun 20 10:09:26 volumio2 volumio[1419]: verbose: ControllerMpd::parseState Jun 20 10:09:26 volumio2 volumio[1419]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 10:09:26 volumio2 volumio[1419]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 20 10:09:26 volumio2 volumio[1419]: verbose: ControllerMpd::parseTrackInfo Jun 20 10:09:26 volumio2 volumio[1419]: info: ControllerMpd::pushState Jun 20 10:09:26 volumio2 volumio[1419]: info: CoreCommandRouter::servicePushState Jun 20 10:09:26 volumio2 volumio[1419]: info: CorePlayQueue::getTrack 5 Jun 20 10:09:26 volumio2 volumio[1419]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":500,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"188 Kbps","isStreaming":false,"title":"0.flac?token=1750410566~NGJhZDZhY2EzNDcwY2RiMzI1Y2Q1NDMzOTEzNmE0ZWZhNzBkMWQ0YQ==","artist":null,"album":null,"uri":"http://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKwgDEidjMjZkYTNiN2Q0ZWQ3YzJjNTJmN2ZlNjg2MWEyMDFkZF82MS5tcDQ/0.flac?token=1750410566~NGJhZDZhY2EzNDcwY2RiMzI1Y2Q1NDMzOTEzNmE0ZWZhNzBkMWQ0YQ==","trackType":"tidal"} Jun 20 10:09:26 volumio2 volumio[1419]: verbose: CURRENT POSITION 5 Jun 20 10:09:26 volumio2 volumio[1419]: info: CoreStateMachine::syncState stateService play Jun 20 10:09:26 volumio2 volumio[1419]: info: CoreStateMachine::syncState currentStatus stop Jun 20 10:09:26 volumio2 volumio[1419]: info: ------------------------------ 28ms Jun 20 10:09:26 volumio2 volumio[1419]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 20 10:09:26 volumio2 volumio[1419]: verbose: ControllerMpd::parseTrackInfo Jun 20 10:09:26 volumio2 volumio[1419]: info: ControllerMpd::pushState Jun 20 10:09:26 volumio2 volumio[1419]: info: CoreCommandRouter::servicePushState Jun 20 10:09:26 volumio2 volumio[1419]: info: CorePlayQueue::getTrack 5 Jun 20 10:09:26 volumio2 volumio[1419]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1209,"duration":500,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"193 Kbps","isStreaming":false,"title":"0.flac?token=1750410566~NGJhZDZhY2EzNDcwY2RiMzI1Y2Q1NDMzOTEzNmE0ZWZhNzBkMWQ0YQ==","artist":null,"album":null,"uri":"http://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKwgDEidjMjZkYTNiN2Q0ZWQ3YzJjNTJmN2ZlNjg2MWEyMDFkZF82MS5tcDQ/0.flac?token=1750410566~NGJhZDZhY2EzNDcwY2RiMzI1Y2Q1NDMzOTEzNmE0ZWZhNzBkMWQ0YQ==","trackType":"tidal"} Jun 20 10:09:26 volumio2 volumio[1419]: verbose: CURRENT POSITION 5 Jun 20 10:09:26 volumio2 volumio[1419]: info: CoreStateMachine::syncState stateService play Jun 20 10:09:26 volumio2 volumio[1419]: info: CoreStateMachine::syncState currentStatus play Jun 20 10:09:26 volumio2 volumio[1419]: info: Received an update from plugin. extracting info from payload Jun 20 10:09:26 volumio2 volumio[1419]: info: CoreStateMachine::pushState Jun 20 10:09:26 volumio2 volumio[1419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 20 10:09:26 volumio2 volumio[1419]: info: CoreCommandRouter::volumioPushState Jun 20 10:09:26 volumio2 volumio[1419]: info: MRS: Pushing multiroomSync output update for this device Jun 20 10:09:26 volumio2 volumio[1419]: info: MRS: Pushing multiroomSync output Jun 20 10:09:26 volumio2 volumio[1419]: info: CoreStateMachine::pushState Jun 20 10:09:26 volumio2 volumio[1419]: info: CoreCommandRouter::volumioPushState Jun 20 10:09:26 volumio2 volumio[1419]: info: MRS: Pushing multiroomSync output update for this device Jun 20 10:09:26 volumio2 volumio[1419]: info: MRS: Pushing multiroomSync output Jun 20 10:09:26 volumio2 volumio[1419]: info: ------------------------------ 29ms Jun 20 10:09:26 volumio2 volumio[1419]: info: FusionDsp - Volumio is playing Jun 20 10:09:26 volumio2 volumio[1419]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Jun 20 10:09:26 volumio2 volumio[1419]: info: FusionDsp - Volumio is playing Jun 20 10:09:26 volumio2 volumio[1419]: info: FusionDsp - Clipping Monitor started Jun 20 10:09:26 volumio2 volumio[1419]: info: FusionDsp - Clipping Monitor started Jun 20 10:09:27 volumio2 volumio[1419]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 20 10:09:27 volumio2 volumio[1419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 20 10:09:27 volumio2 volumio[1419]: info: Discovery: Getting this device information Jun 20 10:09:27 volumio2 volumio[1419]: info: CoreCommandRouter::volumioGetState Jun 20 10:09:27 volumio2 volumio[1419]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 20 10:09:28 volumio2 volumio[1419]: info: Executing endpoint metavolumio Jun 20 10:09:28 volumio2 volumio[1419]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 20 10:09:28 volumio2 volumio[1419]: info: Executing endpoint metavolumio Jun 20 10:09:28 volumio2 volumio[1419]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 20 10:09:28 volumio2 volumio[1419]: info: Executing endpoint metavolumio Jun 20 10:09:28 volumio2 volumio[1419]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 20 10:09:29 volumio2 volumio[1419]: error: Failed request for metavolumio API Jun 20 10:09:30 volumio2 volumio[1419]: error: Failed request for metavolumio API Jun 20 10:09:32 volumio2 volumio[1419]: info: CoreCommandRouter::volumioGetState Jun 20 10:09:32 volumio2 volumio[1419]: info: Listing playlists Jun 20 10:09:32 volumio2 volumio[1419]: info: Listing playlists Jun 20 10:09:42 volumio2 volumio[1419]: info: CoreCommandRouter::volumioGetState Jun 20 10:09:50 volumio2 systemd[1]: systemd-timedated.service: Deactivated successfully. Jun 20 10:09:52 volumio2 volumio[1419]: info: CoreCommandRouter::volumioGetState Jun 20 10:09:52 volumio2 volumio[1419]: info: Listing playlists Jun 20 10:09:52 volumio2 volumio[1419]: info: Listing playlists Jun 20 10:09:57 volumio2 volumio[1419]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 20 10:09:57 volumio2 volumio[1419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 20 10:09:57 volumio2 volumio[1419]: info: Discovery: Getting this device information Jun 20 10:09:57 volumio2 volumio[1419]: info: CoreCommandRouter::volumioGetState Jun 20 10:09:57 volumio2 volumio[1419]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 20 10:10:02 volumio2 volumio[1419]: info: CoreCommandRouter::volumioGetState Jun 20 10:10:12 volumio2 volumio[1419]: info: CoreCommandRouter::volumioGetState Jun 20 10:10:12 volumio2 volumio[1419]: info: Listing playlists Jun 20 10:10:12 volumio2 volumio[1419]: info: Listing playlists Jun 20 10:10:22 volumio2 volumio[1419]: info: CoreCommandRouter::volumioGetState Jun 20 10:10:27 volumio2 volumio[1419]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 20 10:10:27 volumio2 volumio[1419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 20 10:10:27 volumio2 volumio[1419]: info: Discovery: Getting this device information Jun 20 10:10:27 volumio2 volumio[1419]: info: CoreCommandRouter::volumioGetState Jun 20 10:10:27 volumio2 volumio[1419]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 20 10:10:29 volumio2 volumio[1419]: info: CoreCommandRouter::volumioGetState Jun 20 10:10:29 volumio2 sudo[3685]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 20 10:10:29 volumio2 sudo[3685]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 10:10:29 volumio2 sudo[3685]: pam_unix(sudo:session): session closed for user root Jun 20 10:10:29 volumio2 sudo[3688]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 20 10:10:29 volumio2 sudo[3688]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 10:10:29 volumio2 sudo[3688]: pam_unix(sudo:session): session closed for user root Jun 20 10:10:29 volumio2 volumio[1419]: verbose: New Socket.io Connection to 192.168.1.215 from 192.168.1.159 UA: Mozilla/5.0 (Linux; Android 15; SM-S921B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.89 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Jun 20 10:10:30 volumio2 sudo[3692]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 20 10:10:30 volumio2 sudo[3692]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 10:10:30 volumio2 sudo[3692]: pam_unix(sudo:session): session closed for user root Jun 20 10:10:30 volumio2 sudo[3694]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 20 10:10:30 volumio2 sudo[3694]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 10:10:30 volumio2 sudo[3694]: pam_unix(sudo:session): session closed for user root Jun 20 10:10:30 volumio2 volumio[1419]: verbose: New Socket.io Connection to 192.168.1.215 from 192.168.1.159 UA: Mozilla/5.0 (Linux; Android 15; SM-S921B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.89 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 Jun 20 10:10:30 volumio2 volumio[1419]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 20 10:10:30 volumio2 volumio[1419]: info: CoreCommandRouter::volumioGetVisibleSources Jun 20 10:10:30 volumio2 volumio[1419]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 20 10:10:30 volumio2 volumio[1419]: info: CoreCommandRouter::volumioGetState Jun 20 10:10:30 volumio2 volumio[1419]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 20 10:10:30 volumio2 volumio[1419]: info: CoreCommandRouter::volumioGetQueue Jun 20 10:10:30 volumio2 volumio[1419]: info: CoreStateMachine::getQueue Jun 20 10:10:30 volumio2 volumio[1419]: info: CorePlayQueue::getQueue Jun 20 10:10:30 volumio2 volumio[1419]: info: Listing playlists Jun 20 10:10:30 volumio2 volumio[1419]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 20 10:10:30 volumio2 volumio[1419]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jun 20 10:10:30 volumio2 volumio[1419]: info: Received Get System Info Jun 20 10:10:30 volumio2 volumio[1419]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 20 10:10:30 volumio2 volumio[1419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 20 10:10:30 volumio2 volumio[1419]: info: Discovery: Getting this device information Jun 20 10:10:30 volumio2 volumio[1419]: info: CoreCommandRouter::volumioGetState Jun 20 10:10:30 volumio2 volumio[1419]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 20 10:10:30 volumio2 volumio[1419]: info: CoreCommandRouter::volumioGetState Jun 20 10:10:30 volumio2 volumio[1419]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 20 10:10:30 volumio2 volumio[1419]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 20 10:10:30 volumio2 volumio[1419]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 20 10:10:30 volumio2 volumio[1419]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 20 10:10:31 volumio2 volumio[1419]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jun 20 10:10:32 volumio2 volumio[1419]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 20 10:10:32 volumio2 volumio[1419]: info: Received Get System Info Jun 20 10:10:32 volumio2 volumio[1419]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 20 10:10:32 volumio2 volumio[1419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 20 10:10:32 volumio2 volumio[1419]: info: Discovery: Getting this device information Jun 20 10:10:32 volumio2 volumio[1419]: info: CoreCommandRouter::volumioGetState Jun 20 10:10:32 volumio2 volumio[1419]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 20 10:10:32 volumio2 volumio[1419]: info: CoreCommandRouter::volumioGetState Jun 20 10:10:32 volumio2 volumio[1419]: info: Listing playlists Jun 20 10:10:32 volumio2 volumio[1419]: info: Listing playlists Jun 20 10:10:32 volumio2 volumio[1419]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 20 10:10:32 volumio2 volumio[1419]: info: Received Get System Info Jun 20 10:10:32 volumio2 volumio[1419]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 20 10:10:32 volumio2 volumio[1419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 20 10:10:32 volumio2 volumio[1419]: info: Discovery: Getting this device information Jun 20 10:10:32 volumio2 volumio[1419]: info: CoreCommandRouter::volumioGetState Jun 20 10:10:32 volumio2 volumio[1419]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 20 10:10:40 volumio2 volumio[1419]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 20 10:10:40 volumio2 volumio[1419]: Searching all installed plugins Jun 20 10:10:40 volumio2 volumio[1419]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 20 10:10:40 volumio2 volumio[1419]: info: CoreCommandRouter::executeOnPlugin: , search Jun 20 10:10:40 volumio2 volumio[1419]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jun 20 10:10:40 volumio2 volumio[1419]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Jun 20 10:10:40 volumio2 volumio[1419]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Jun 20 10:10:40 volumio2 volumio[1419]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Jun 20 10:10:40 volumio2 volumio[1419]: info: CoreCommandRouter::executeOnPlugin: webradio , search Jun 20 10:10:40 volumio2 volumio[1419]: info: CoreCommandRouter::executeOnPlugin: smart_inputs , search Jun 20 10:10:40 volumio2 volumio[1419]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin smart_inputs Jun 20 10:10:40 volumio2 volumio[1419]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Jun 20 10:10:40 volumio2 volumio[1419]: info: CoreCommandRouter::executeOnPlugin: tidal , search Jun 20 10:10:40 volumio2 volumio[1419]: info: searchTIDALUri took 235 milliseconds Jun 20 10:10:40 volumio2 volumio[1419]: info: search took 236 milliseconds Jun 20 10:10:40 volumio2 volumio[1419]: Use XMLElement.setAttribute instead Jun 20 10:10:40 volumio2 volumio[1419]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 20 10:10:40 volumio2 volumio[1419]: TypeError: children[i].attr(...).value is not a function Jun 20 10:10:40 volumio2 volumio[1419]: at /volumio/app/plugins/music_service/webradio/index.js:956:46 Jun 20 10:10:40 volumio2 volumio[1419]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Jun 20 10:10:40 volumio2 volumio[1419]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 20 10:10:40 volumio2 sudo[3737]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-06-20 10:09' Jun 20 10:10:40 volumio2 sudo[3737]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="cee9bb5b0ee50e4898478097a64b5c6c77d5a212" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Jun 14 07:01:29 UTC 2025" VOLUMIO_VERSION="4.012" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="38c4f0e007cb9b6482e9f27bbe65b0b5"