-- Logs begin at Thu 2019-02-14 12:11:58 EET, end at Mon 2025-09-08 21:30:26 EEST. -- Sep 08 21:29:08 volumio-p volumio[1195]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 08 21:29:08 volumio-p volumio[1195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 08 21:29:08 volumio-p volumio[1195]: info: Discovery: Getting this device information Sep 08 21:29:08 volumio-p volumio[1195]: info: CoreCommandRouter::volumioGetState Sep 08 21:29:08 volumio-p volumio[1195]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 08 21:29:13 volumio-p volumiologrotate[610]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Sep 08 21:29:13 volumio-p volumiologrotate[610]: ls: cannot access 'P': No such file or directory Sep 08 21:29:35 volumio-p volumio[1195]: verbose: New Socket.io Connection to 192.168.1.222 from 192.168.1.28 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_6_2 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 6 Sep 08 21:29:35 volumio-p volumio[1195]: info: CoreCommandRouter::volumioGetState Sep 08 21:29:35 volumio-p volumio[1195]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 08 21:29:35 volumio-p volumio[1195]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Sep 08 21:29:35 volumio-p volumio[1195]: info: Listing playlists Sep 08 21:29:37 volumio-p volumio[1195]: info: VolumeController::SetAlsaVolume62 Sep 08 21:29:37 volumio-p volumio[1195]: info: CoreStateMachine::pushState Sep 08 21:29:37 volumio-p volumio[1195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 08 21:29:37 volumio-p volumio[1195]: info: CoreCommandRouter::volumioPushState Sep 08 21:29:37 volumio-p volumio[1195]: info: MRS: Pushing multiroomSync output update for this device Sep 08 21:29:37 volumio-p volumio[1195]: info: MRS: Pushing multiroomSync output Sep 08 21:29:37 volumio-p volumio[1195]: info: CoreCommandRouter::volumioGetState Sep 08 21:29:38 volumio-p volumio[1195]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 08 21:29:38 volumio-p volumio[1195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 08 21:29:38 volumio-p volumio[1195]: info: Discovery: Getting this device information Sep 08 21:29:38 volumio-p volumio[1195]: info: CoreCommandRouter::volumioGetState Sep 08 21:29:38 volumio-p volumio[1195]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 08 21:29:39 volumio-p volumio[1195]: info: CoreCommandRouter::volumioGetVisibleSources Sep 08 21:29:39 volumio-p volumio[1195]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 08 21:29:39 volumio-p volumio[1195]: info: CoreCommandRouter::volumioGetQueue Sep 08 21:29:39 volumio-p volumio[1195]: info: CoreStateMachine::getQueue Sep 08 21:29:39 volumio-p volumio[1195]: info: CorePlayQueue::getQueue Sep 08 21:29:43 volumio-p volumio[1195]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Sep 08 21:29:44 volumio-p volumio[1195]: info: browseTIDALUri took 1052 milliseconds Sep 08 21:29:45 volumio-p volumio[1195]: info: Preload queue cleared Sep 08 21:29:45 volumio-p volumio[1195]: info: Preload queue cleared Sep 08 21:29:46 volumio-p volumio[1195]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Sep 08 21:29:46 volumio-p volumio[1195]: info: browseTIDALUri took 313 milliseconds Sep 08 21:29:46 volumio-p volumio[1195]: info: Preload queue cleared Sep 08 21:29:46 volumio-p volumio[1195]: info: Preload queue cleared Sep 08 21:29:51 volumio-p volumio[1195]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Sep 08 21:29:51 volumio-p volumio[1195]: info: browseTIDALUri took 403 milliseconds Sep 08 21:29:51 volumio-p volumio[1195]: info: Preload queue cleared Sep 08 21:29:51 volumio-p volumio[1195]: info: Preloading song: tidal://song/52027341 Sep 08 21:29:51 volumio-p volumio[1195]: info: Preloading song: tidal://song/105769707 Sep 08 21:29:51 volumio-p volumio[1195]: info: Preloading song: tidal://song/37627573 Sep 08 21:29:51 volumio-p volumio[1195]: info: Preloading song: tidal://song/14504080 Sep 08 21:29:51 volumio-p volumio[1195]: info: Preloading song: tidal://song/173669622 Sep 08 21:29:51 volumio-p volumio[1195]: info: Preloading song: tidal://song/253992134 Sep 08 21:29:51 volumio-p volumio[1195]: info: Preloading song: tidal://song/246861737 Sep 08 21:29:51 volumio-p volumio[1195]: info: Preloading song: tidal://song/16746504 Sep 08 21:29:51 volumio-p volumio[1195]: info: Exploding uri tidal://song/52027341 in service tidal Sep 08 21:29:51 volumio-p volumio[1195]: info: Exploding uri tidal://song/105769707 in service tidal Sep 08 21:29:51 volumio-p volumio[1195]: info: Exploding uri tidal://song/37627573 in service tidal Sep 08 21:29:51 volumio-p volumio[1195]: info: Exploding uri tidal://song/14504080 in service tidal Sep 08 21:29:51 volumio-p volumio[1195]: info: Exploding uri tidal://song/173669622 in service tidal Sep 08 21:29:51 volumio-p volumio[1195]: info: explodeTIDALUri took 246 milliseconds Sep 08 21:29:51 volumio-p volumio[1195]: info: Exploding uri tidal://song/253992134 in service tidal Sep 08 21:29:51 volumio-p volumio[1195]: info: explodeTIDALUri took 248 milliseconds Sep 08 21:29:51 volumio-p volumio[1195]: info: Exploding uri tidal://song/246861737 in service tidal Sep 08 21:29:51 volumio-p volumio[1195]: info: Exploding uri tidal://song/16746504 in service tidal Sep 08 21:29:51 volumio-p volumio[1195]: info: explodeTIDALUri took 256 milliseconds Sep 08 21:29:52 volumio-p volumio[1195]: info: explodeTIDALUri took 250 milliseconds Sep 08 21:29:52 volumio-p volumio[1195]: info: explodeTIDALUri took 246 milliseconds Sep 08 21:29:52 volumio-p volumio[1195]: info: explodeTIDALUri took 240 milliseconds Sep 08 21:29:52 volumio-p volumio[1195]: info: explodeTIDALUri took 302 milliseconds Sep 08 21:29:52 volumio-p volumio[1195]: info: explodeTIDALUri took 240 milliseconds Sep 08 21:29:53 volumio-p volumio[1195]: info: Preload queue cleared Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreStateMachine::ClearQueue Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreStateMachine::stop Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreStateMachine::updateTrackBlock Sep 08 21:29:53 volumio-p volumio[1195]: info: CorePlayQueue::getTrackBlock Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreStateMachine::stPlaybackTimer Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreStateMachine::pushState Sep 08 21:29:53 volumio-p volumio[1195]: info: CorePlayQueue::getTrack 1 Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreCommandRouter::volumioPushState Sep 08 21:29:53 volumio-p volumio[1195]: info: MRS: Pushing multiroomSync output update for this device Sep 08 21:29:53 volumio-p volumio[1195]: info: MRS: Pushing multiroomSync output Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreCommandRouter::volumioGetState Sep 08 21:29:53 volumio-p volumio[1195]: info: CorePlayQueue::getTrack 1 Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreStateMachine::serviceStop Sep 08 21:29:53 volumio-p volumio[1195]: info: CorePlayQueue::getTrack 1 Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreCommandRouter::serviceStop Sep 08 21:29:53 volumio-p volumio[1195]: info: [1757356193016] ControllerTidal::stop Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 08 21:29:53 volumio-p volumio[1195]: info: ControllerMpd::stop Sep 08 21:29:53 volumio-p volumio[1195]: verbose: ControllerMpd::sendMpdCommand stop Sep 08 21:29:53 volumio-p volumio[1195]: info: CorePlayQueue::clearPlayQueue Sep 08 21:29:53 volumio-p volumio[1195]: info: CorePlayQueue::saveQueue Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreCommandRouter::volumioPushQueue Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreStateMachine::addQueueItems Sep 08 21:29:53 volumio-p volumio[1195]: info: CorePlayQueue::addQueueItems Sep 08 21:29:53 volumio-p volumio[1195]: info: Preload queue cleared Sep 08 21:29:53 volumio-p volumio[1195]: info: Adding Item to queue: tidal://song/52027341 Sep 08 21:29:53 volumio-p volumio[1195]: info: Using cached record of: tidal://song/52027341 Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreCommandRouter::volumioPushQueue Sep 08 21:29:53 volumio-p volumio[1195]: info: CorePlayQueue::saveQueue Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreStateMachine::updateTrackBlock Sep 08 21:29:53 volumio-p volumio[1195]: info: CorePlayQueue::getTrackBlock Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreCommandRouter::volumioPlay Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreStateMachine::play index 0 Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreStateMachine::addQueueItems Sep 08 21:29:53 volumio-p volumio[1195]: info: CorePlayQueue::addQueueItems Sep 08 21:29:53 volumio-p volumio[1195]: info: Preload queue cleared Sep 08 21:29:53 volumio-p volumio[1195]: info: Adding Item to queue: tidal://song/105769707 Sep 08 21:29:53 volumio-p volumio[1195]: info: Using cached record of: tidal://song/105769707 Sep 08 21:29:53 volumio-p volumio[1195]: info: Adding Item to queue: tidal://song/37627573 Sep 08 21:29:53 volumio-p volumio[1195]: info: Using cached record of: tidal://song/37627573 Sep 08 21:29:53 volumio-p volumio[1195]: info: Adding Item to queue: tidal://song/14504080 Sep 08 21:29:53 volumio-p volumio[1195]: info: Using cached record of: tidal://song/14504080 Sep 08 21:29:53 volumio-p volumio[1195]: info: Adding Item to queue: tidal://song/173669622 Sep 08 21:29:53 volumio-p volumio[1195]: info: Using cached record of: tidal://song/173669622 Sep 08 21:29:53 volumio-p volumio[1195]: info: Adding Item to queue: tidal://song/253992134 Sep 08 21:29:53 volumio-p volumio[1195]: info: Using cached record of: tidal://song/253992134 Sep 08 21:29:53 volumio-p volumio[1195]: info: Adding Item to queue: tidal://song/246861737 Sep 08 21:29:53 volumio-p volumio[1195]: info: Using cached record of: tidal://song/246861737 Sep 08 21:29:53 volumio-p volumio[1195]: info: Adding Item to queue: tidal://song/16746504 Sep 08 21:29:53 volumio-p volumio[1195]: info: Using cached record of: tidal://song/16746504 Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreStateMachine::stop Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreCommandRouter::volumioPushQueue Sep 08 21:29:53 volumio-p volumio[1195]: info: CorePlayQueue::saveQueue Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreStateMachine::play index undefined Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreStateMachine::updateTrackBlock Sep 08 21:29:53 volumio-p volumio[1195]: info: CorePlayQueue::getTrackBlock Sep 08 21:29:53 volumio-p volumio[1195]: info: CorePlayQueue::getTrack 0 Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreStateMachine::startPlaybackTimer Sep 08 21:29:53 volumio-p volumio[1195]: info: CorePlayQueue::getTrack 0 Sep 08 21:29:53 volumio-p volumio[1195]: info: [1757356193045] ControllerTidal::clearAddPlayTrack Sep 08 21:29:53 volumio-p volumio[1195]: info: Getting stream with soundQuality LOSSLESS Sep 08 21:29:53 volumio-p volumio[1195]: verbose: ControllerMpd::sendMpdCommand stop Sep 08 21:29:53 volumio-p volumio[1195]: info: sendMpdCommand stop took 35 milliseconds Sep 08 21:29:53 volumio-p volumio[1195]: info: sendMpdCommand stop took 6 milliseconds Sep 08 21:29:53 volumio-p volumio[1195]: verbose: ControllerMpd::sendMpdCommand clear Sep 08 21:29:53 volumio-p volumio[1195]: info: Sep 08 21:29:53 volumio-p volumio[1195]: ---------------------------- MPD announces system playlist update Sep 08 21:29:53 volumio-p volumio[1195]: info: Ignoring MPD Status Update Sep 08 21:29:53 volumio-p volumio[1195]: info: sendMpdCommand clear took 1 milliseconds Sep 08 21:29:53 volumio-p volumio[1195]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/52027341&soundQuality=LOSSLESS" Sep 08 21:29:53 volumio-p volumio[1195]: info: Sep 08 21:29:53 volumio-p volumio[1195]: ---------------------------- MPD announces system playlist update Sep 08 21:29:53 volumio-p volumio[1195]: info: Ignoring MPD Status Update Sep 08 21:29:53 volumio-p volumio[1195]: info: Sep 08 21:29:53 volumio-p volumio[1195]: ---------------------------- MPD announces system playlist update Sep 08 21:29:53 volumio-p volumio[1195]: info: Ignoring MPD Status Update Sep 08 21:29:53 volumio-p volumio[1195]: error: updateQueue error: null Sep 08 21:29:53 volumio-p volumio[1195]: info: Sep 08 21:29:53 volumio-p volumio[1195]: ---------------------------- MPD announces system playlist update Sep 08 21:29:53 volumio-p volumio[1195]: info: Ignoring MPD Status Update Sep 08 21:29:53 volumio-p volumio[1195]: info: ------------------------------ 3ms Sep 08 21:29:53 volumio-p volumio[1195]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/52027341&soundQuality=LOSSLESS" took 3 milliseconds Sep 08 21:29:53 volumio-p volumio[1195]: info: ------------------------------ 3ms Sep 08 21:29:53 volumio-p volumio[1195]: info: ------------------------------ 2ms Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 08 21:29:53 volumio-p volumio[1195]: verbose: ControllerMpd::sendMpdCommand play Sep 08 21:29:53 volumio-p volumio[1195]: info: Sep 08 21:29:53 volumio-p volumio[1195]: ---------------------------- MPD announces system playlist update Sep 08 21:29:53 volumio-p volumio[1195]: info: Ignoring MPD Status Update Sep 08 21:29:53 volumio-p volumio[1195]: info: Sep 08 21:29:53 volumio-p volumio[1195]: ---------------------------- MPD announces system playlist update Sep 08 21:29:53 volumio-p volumio[1195]: info: Ignoring MPD Status Update Sep 08 21:29:53 volumio-p volumio[1195]: STREAMING PROXY: Handling url /?data=tidal://song/52027341&soundQuality=LOSSLESS Sep 08 21:29:53 volumio-p volumio[1195]: info: ------------------------------ 4ms Sep 08 21:29:53 volumio-p volumio[1195]: info: sendMpdCommand play took 3 milliseconds Sep 08 21:29:53 volumio-p volumio[1195]: info: ------------------------------ 2ms Sep 08 21:29:53 volumio-p volumio[1195]: info: ------------------------------ 2ms Sep 08 21:29:53 volumio-p volumio[1195]: info: Executing endpoint getStreamUrltidal Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Sep 08 21:29:53 volumio-p volumio[1195]: info: getStreamUrl took 273 milliseconds Sep 08 21:29:53 volumio-p volumio[1195]: STREAMING PROXY: Got real url: http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEicxYTRkYmQxMzBiNWM3ZGFhMjQ2ZDNjNGQ3MWRkY2RkMl82MS5tcDQ/0.flac?token=1757359793~MGUwYTIxYTVmOTlmMmEyZTg1YTM1M2QwYjBkMDBhYzExOWYwMzFkNA== Sep 08 21:29:53 volumio-p volumio[1195]: STREAMING PROXY: Response: 200, length: 22272614 Sep 08 21:29:53 volumio-p volumio[1195]: info: Sep 08 21:29:53 volumio-p volumio[1195]: ---------------------------- MPD announces state update: player Sep 08 21:29:53 volumio-p volumio[1195]: info: ControllerMpd::getState Sep 08 21:29:53 volumio-p volumio[1195]: verbose: ControllerMpd::sendMpdCommand status Sep 08 21:29:53 volumio-p volumio[1195]: info: Sep 08 21:29:53 volumio-p volumio[1195]: ---------------------------- MPD announces state update: player Sep 08 21:29:53 volumio-p volumio[1195]: info: ControllerMpd::getState Sep 08 21:29:53 volumio-p volumio[1195]: verbose: ControllerMpd::sendMpdCommand status Sep 08 21:29:53 volumio-p volumio[1195]: info: Sep 08 21:29:53 volumio-p volumio[1195]: ---------------------------- MPD announces state update: player Sep 08 21:29:53 volumio-p volumio[1195]: info: ControllerMpd::getState Sep 08 21:29:53 volumio-p volumio[1195]: verbose: ControllerMpd::sendMpdCommand status Sep 08 21:29:53 volumio-p volumio[1195]: info: Sep 08 21:29:53 volumio-p volumio[1195]: ---------------------------- MPD announces state update: player Sep 08 21:29:53 volumio-p volumio[1195]: info: sendMpdCommand status took 201 milliseconds Sep 08 21:29:53 volumio-p volumio[1195]: info: ControllerMpd::getState Sep 08 21:29:53 volumio-p volumio[1195]: verbose: ControllerMpd::sendMpdCommand status Sep 08 21:29:53 volumio-p volumio[1195]: verbose: ControllerMpd::parseState Sep 08 21:29:53 volumio-p volumio[1195]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 21:29:53 volumio-p volumio[1195]: info: sendMpdCommand status took 202 milliseconds Sep 08 21:29:53 volumio-p volumio[1195]: info: sendMpdCommand status took 201 milliseconds Sep 08 21:29:53 volumio-p volumio[1195]: info: sendMpdCommand status took 3 milliseconds Sep 08 21:29:53 volumio-p volumio[1195]: verbose: ControllerMpd::parseState Sep 08 21:29:53 volumio-p volumio[1195]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 21:29:53 volumio-p volumio[1195]: verbose: ControllerMpd::parseState Sep 08 21:29:53 volumio-p volumio[1195]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 21:29:53 volumio-p volumio[1195]: verbose: ControllerMpd::parseState Sep 08 21:29:53 volumio-p volumio[1195]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 21:29:53 volumio-p volumio[1195]: info: Sep 08 21:29:53 volumio-p volumio[1195]: ---------------------------- MPD announces state update: player Sep 08 21:29:53 volumio-p volumio[1195]: info: ControllerMpd::getState Sep 08 21:29:53 volumio-p volumio[1195]: verbose: ControllerMpd::sendMpdCommand status Sep 08 21:29:53 volumio-p volumio[1195]: info: Sep 08 21:29:53 volumio-p volumio[1195]: ---------------------------- MPD announces state update: player Sep 08 21:29:53 volumio-p volumio[1195]: info: ControllerMpd::getState Sep 08 21:29:53 volumio-p volumio[1195]: verbose: ControllerMpd::sendMpdCommand status Sep 08 21:29:53 volumio-p volumio[1195]: info: sendMpdCommand playlistinfo took 12 milliseconds Sep 08 21:29:53 volumio-p volumio[1195]: info: sendMpdCommand playlistinfo took 9 milliseconds Sep 08 21:29:53 volumio-p volumio[1195]: info: sendMpdCommand playlistinfo took 8 milliseconds Sep 08 21:29:53 volumio-p volumio[1195]: info: sendMpdCommand status took 5 milliseconds Sep 08 21:29:53 volumio-p volumio[1195]: verbose: ControllerMpd::parseTrackInfo Sep 08 21:29:53 volumio-p volumio[1195]: verbose: ControllerMpd::parseTrackInfo Sep 08 21:29:53 volumio-p volumio[1195]: verbose: ControllerMpd::parseTrackInfo Sep 08 21:29:53 volumio-p volumio[1195]: verbose: ControllerMpd::parseState Sep 08 21:29:53 volumio-p volumio[1195]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 21:29:53 volumio-p volumio[1195]: info: ControllerMpd::pushState Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreCommandRouter::servicePushState Sep 08 21:29:53 volumio-p volumio[1195]: info: CorePlayQueue::getTrack 0 Sep 08 21:29:53 volumio-p volumio[1195]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":238,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"514 Kbps","isStreaming":false,"title":"52027341&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/52027341&soundQuality=LOSSLESS","trackType":"tidal"} Sep 08 21:29:53 volumio-p volumio[1195]: verbose: CURRENT POSITION 0 Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreStateMachine::syncState stateService play Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreStateMachine::syncState currentStatus stop Sep 08 21:29:53 volumio-p volumio[1195]: info: ControllerMpd::pushState Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreCommandRouter::servicePushState Sep 08 21:29:53 volumio-p volumio[1195]: info: CorePlayQueue::getTrack 0 Sep 08 21:29:53 volumio-p volumio[1195]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":238,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"489 Kbps","isStreaming":false,"title":"52027341&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/52027341&soundQuality=LOSSLESS","trackType":"tidal"} Sep 08 21:29:53 volumio-p volumio[1195]: verbose: CURRENT POSITION 0 Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreStateMachine::syncState stateService play Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreStateMachine::syncState currentStatus play Sep 08 21:29:53 volumio-p volumio[1195]: info: Received an update from plugin. extracting info from payload Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreStateMachine::pushState Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreCommandRouter::volumioPushState Sep 08 21:29:53 volumio-p volumio[1195]: info: MRS: Pushing multiroomSync output update for this device Sep 08 21:29:53 volumio-p volumio[1195]: info: MRS: Pushing multiroomSync output Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreCommandRouter::volumioGetState Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreStateMachine::pushState Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreCommandRouter::volumioPushState Sep 08 21:29:53 volumio-p volumio[1195]: info: MRS: Pushing multiroomSync output update for this device Sep 08 21:29:53 volumio-p volumio[1195]: info: MRS: Pushing multiroomSync output Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreCommandRouter::volumioGetState Sep 08 21:29:53 volumio-p volumio[1195]: info: ControllerMpd::pushState Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreCommandRouter::servicePushState Sep 08 21:29:53 volumio-p volumio[1195]: info: CorePlayQueue::getTrack 0 Sep 08 21:29:53 volumio-p volumio[1195]: verbose: STATE SERVICE {"status":"play","position":0,"seek":45,"duration":238,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"489 Kbps","isStreaming":false,"title":"52027341&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/52027341&soundQuality=LOSSLESS","trackType":"tidal"} Sep 08 21:29:53 volumio-p volumio[1195]: verbose: CURRENT POSITION 0 Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreStateMachine::syncState stateService play Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreStateMachine::syncState currentStatus play Sep 08 21:29:53 volumio-p volumio[1195]: info: Received an update from plugin. extracting info from payload Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreStateMachine::pushState Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreCommandRouter::volumioPushState Sep 08 21:29:53 volumio-p volumio[1195]: info: MRS: Pushing multiroomSync output update for this device Sep 08 21:29:53 volumio-p volumio[1195]: info: MRS: Pushing multiroomSync output Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreCommandRouter::volumioGetState Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreStateMachine::pushState Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreCommandRouter::volumioPushState Sep 08 21:29:53 volumio-p volumio[1195]: info: MRS: Pushing multiroomSync output update for this device Sep 08 21:29:53 volumio-p volumio[1195]: info: MRS: Pushing multiroomSync output Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreCommandRouter::volumioGetState Sep 08 21:29:53 volumio-p volumio[1195]: info: ControllerMpd::pushState Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreCommandRouter::servicePushState Sep 08 21:29:53 volumio-p volumio[1195]: info: CorePlayQueue::getTrack 0 Sep 08 21:29:53 volumio-p volumio[1195]: verbose: STATE SERVICE {"status":"play","position":0,"seek":114,"duration":238,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"489 Kbps","isStreaming":false,"title":"52027341&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/52027341&soundQuality=LOSSLESS","trackType":"tidal"} Sep 08 21:29:53 volumio-p volumio[1195]: verbose: CURRENT POSITION 0 Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreStateMachine::syncState stateService play Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreStateMachine::syncState currentStatus play Sep 08 21:29:53 volumio-p volumio[1195]: info: Received an update from plugin. extracting info from payload Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreStateMachine::pushState Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreCommandRouter::volumioPushState Sep 08 21:29:53 volumio-p volumio[1195]: info: MRS: Pushing multiroomSync output update for this device Sep 08 21:29:53 volumio-p volumio[1195]: info: MRS: Pushing multiroomSync output Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreCommandRouter::volumioGetState Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreStateMachine::pushState Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreCommandRouter::volumioPushState Sep 08 21:29:53 volumio-p volumio[1195]: info: MRS: Pushing multiroomSync output update for this device Sep 08 21:29:53 volumio-p volumio[1195]: info: MRS: Pushing multiroomSync output Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreCommandRouter::volumioGetState Sep 08 21:29:53 volumio-p volumio[1195]: info: ------------------------------ 256ms Sep 08 21:29:53 volumio-p volumio[1195]: info: ------------------------------ 329ms Sep 08 21:29:53 volumio-p volumio[1195]: info: ------------------------------ 328ms Sep 08 21:29:53 volumio-p volumio[1195]: info: ------------------------------ 131ms Sep 08 21:29:53 volumio-p volumio[1195]: info: sendMpdCommand status took 120 milliseconds Sep 08 21:29:53 volumio-p volumio[1195]: info: sendMpdCommand playlistinfo took 116 milliseconds Sep 08 21:29:53 volumio-p volumio[1195]: verbose: ControllerMpd::parseState Sep 08 21:29:53 volumio-p volumio[1195]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 21:29:53 volumio-p volumio[1195]: verbose: ControllerMpd::parseTrackInfo Sep 08 21:29:53 volumio-p volumio[1195]: info: ControllerMpd::pushState Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreCommandRouter::servicePushState Sep 08 21:29:53 volumio-p volumio[1195]: info: CorePlayQueue::getTrack 0 Sep 08 21:29:53 volumio-p volumio[1195]: verbose: STATE SERVICE {"status":"play","position":0,"seek":388,"duration":238,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"390 Kbps","isStreaming":false,"title":"52027341&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/52027341&soundQuality=LOSSLESS","trackType":"tidal"} Sep 08 21:29:53 volumio-p volumio[1195]: verbose: CURRENT POSITION 0 Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreStateMachine::syncState stateService play Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreStateMachine::syncState currentStatus play Sep 08 21:29:53 volumio-p volumio[1195]: info: Received an update from plugin. extracting info from payload Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreStateMachine::pushState Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreCommandRouter::volumioPushState Sep 08 21:29:53 volumio-p volumio[1195]: info: MRS: Pushing multiroomSync output update for this device Sep 08 21:29:53 volumio-p volumio[1195]: info: MRS: Pushing multiroomSync output Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreCommandRouter::volumioGetState Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreStateMachine::pushState Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreCommandRouter::volumioPushState Sep 08 21:29:53 volumio-p volumio[1195]: info: MRS: Pushing multiroomSync output update for this device Sep 08 21:29:53 volumio-p volumio[1195]: info: MRS: Pushing multiroomSync output Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreCommandRouter::volumioGetState Sep 08 21:29:53 volumio-p volumio[1195]: info: ------------------------------ 165ms Sep 08 21:29:53 volumio-p volumio[1195]: info: sendMpdCommand playlistinfo took 87 milliseconds Sep 08 21:29:53 volumio-p volumio[1195]: verbose: ControllerMpd::parseTrackInfo Sep 08 21:29:53 volumio-p volumio[1195]: info: ControllerMpd::pushState Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreCommandRouter::servicePushState Sep 08 21:29:53 volumio-p volumio[1195]: info: CorePlayQueue::getTrack 0 Sep 08 21:29:53 volumio-p volumio[1195]: verbose: STATE SERVICE {"status":"play","position":0,"seek":777,"duration":238,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"390 Kbps","isStreaming":false,"title":"52027341&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/52027341&soundQuality=LOSSLESS","trackType":"tidal"} Sep 08 21:29:53 volumio-p volumio[1195]: verbose: CURRENT POSITION 0 Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreStateMachine::syncState stateService play Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreStateMachine::syncState currentStatus play Sep 08 21:29:53 volumio-p volumio[1195]: info: Received an update from plugin. extracting info from payload Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreStateMachine::pushState Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreCommandRouter::volumioPushState Sep 08 21:29:53 volumio-p volumio[1195]: info: MRS: Pushing multiroomSync output update for this device Sep 08 21:29:53 volumio-p volumio[1195]: info: MRS: Pushing multiroomSync output Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreCommandRouter::volumioGetState Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreStateMachine::pushState Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreCommandRouter::volumioPushState Sep 08 21:29:53 volumio-p volumio[1195]: info: MRS: Pushing multiroomSync output update for this device Sep 08 21:29:53 volumio-p volumio[1195]: info: MRS: Pushing multiroomSync output Sep 08 21:29:53 volumio-p volumio[1195]: info: CoreCommandRouter::volumioGetState Sep 08 21:29:53 volumio-p volumio[1195]: info: ------------------------------ 249ms Sep 08 21:29:53 volumio-p volumio[1195]: STREAMING PROXY: Client dropped request, destroying Sep 08 21:29:55 volumio-p volumio[1195]: info: VolumeController::SetAlsaVolume58 Sep 08 21:29:55 volumio-p volumio[1195]: info: CoreStateMachine::pushState Sep 08 21:29:55 volumio-p volumio[1195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 08 21:29:55 volumio-p volumio[1195]: info: CoreCommandRouter::volumioPushState Sep 08 21:29:55 volumio-p volumio[1195]: info: MRS: Pushing multiroomSync output update for this device Sep 08 21:29:55 volumio-p volumio[1195]: info: MRS: Pushing multiroomSync output Sep 08 21:29:55 volumio-p volumio[1195]: info: CoreCommandRouter::volumioGetState Sep 08 21:29:57 volumio-p volumio[1195]: info: CoreCommandRouter::volumioPause Sep 08 21:29:57 volumio-p volumio[1195]: info: CoreStateMachine::pause Sep 08 21:29:57 volumio-p volumio[1195]: info: CoreStateMachine::stPlaybackTimer Sep 08 21:29:57 volumio-p volumio[1195]: info: CoreStateMachine::servicePause Sep 08 21:29:57 volumio-p volumio[1195]: info: CorePlayQueue::getTrack 0 Sep 08 21:29:57 volumio-p volumio[1195]: info: CoreCommandRouter::servicePause Sep 08 21:29:57 volumio-p volumio[1195]: info: [1757356197744] ControllerTidal::pause Sep 08 21:29:57 volumio-p volumio[1195]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 08 21:29:57 volumio-p volumio[1195]: info: ControllerMpd::pause Sep 08 21:29:57 volumio-p volumio[1195]: verbose: ControllerMpd::sendMpdCommand pause Sep 08 21:29:57 volumio-p volumio[1195]: info: Sep 08 21:29:57 volumio-p volumio[1195]: ---------------------------- MPD announces state update: player Sep 08 21:29:57 volumio-p volumio[1195]: info: sendMpdCommand pause took 1 milliseconds Sep 08 21:29:57 volumio-p volumio[1195]: info: ControllerMpd::getState Sep 08 21:29:57 volumio-p volumio[1195]: verbose: ControllerMpd::sendMpdCommand status Sep 08 21:29:57 volumio-p volumio[1195]: info: Sep 08 21:29:57 volumio-p volumio[1195]: ---------------------------- MPD announces state update: player Sep 08 21:29:57 volumio-p volumio[1195]: info: ControllerMpd::getState Sep 08 21:29:57 volumio-p volumio[1195]: verbose: ControllerMpd::sendMpdCommand status Sep 08 21:29:57 volumio-p volumio[1195]: info: Sep 08 21:29:57 volumio-p volumio[1195]: ---------------------------- MPD announces state update: player Sep 08 21:29:57 volumio-p volumio[1195]: info: ControllerMpd::getState Sep 08 21:29:57 volumio-p volumio[1195]: verbose: ControllerMpd::sendMpdCommand status Sep 08 21:29:57 volumio-p volumio[1195]: info: sendMpdCommand status took 3 milliseconds Sep 08 21:29:57 volumio-p volumio[1195]: info: sendMpdCommand status took 1 milliseconds Sep 08 21:29:57 volumio-p volumio[1195]: info: sendMpdCommand status took 2 milliseconds Sep 08 21:29:57 volumio-p volumio[1195]: verbose: ControllerMpd::parseState Sep 08 21:29:57 volumio-p volumio[1195]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 21:29:57 volumio-p volumio[1195]: verbose: ControllerMpd::parseState Sep 08 21:29:57 volumio-p volumio[1195]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 21:29:57 volumio-p volumio[1195]: verbose: ControllerMpd::parseState Sep 08 21:29:57 volumio-p volumio[1195]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 21:29:57 volumio-p volumio[1195]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 08 21:29:57 volumio-p volumio[1195]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 08 21:29:57 volumio-p volumio[1195]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 08 21:29:57 volumio-p volumio[1195]: verbose: ControllerMpd::parseTrackInfo Sep 08 21:29:57 volumio-p volumio[1195]: verbose: ControllerMpd::parseTrackInfo Sep 08 21:29:57 volumio-p volumio[1195]: verbose: ControllerMpd::parseTrackInfo Sep 08 21:29:57 volumio-p volumio[1195]: info: ControllerMpd::pushState Sep 08 21:29:57 volumio-p volumio[1195]: info: CoreCommandRouter::servicePushState Sep 08 21:29:57 volumio-p volumio[1195]: info: CorePlayQueue::getTrack 0 Sep 08 21:29:57 volumio-p volumio[1195]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":5353,"duration":238,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"585 Kbps","isStreaming":false,"title":"52027341&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/52027341&soundQuality=LOSSLESS","trackType":"tidal"} Sep 08 21:29:57 volumio-p volumio[1195]: verbose: CURRENT POSITION 0 Sep 08 21:29:57 volumio-p volumio[1195]: info: CoreStateMachine::syncState stateService pause Sep 08 21:29:57 volumio-p volumio[1195]: info: CoreStateMachine::syncState currentStatus pause Sep 08 21:29:57 volumio-p volumio[1195]: info: CoreStateMachine::pushState Sep 08 21:29:57 volumio-p volumio[1195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 08 21:29:57 volumio-p volumio[1195]: info: CoreCommandRouter::volumioPushState Sep 08 21:29:57 volumio-p volumio[1195]: info: MRS: Pushing multiroomSync output update for this device Sep 08 21:29:57 volumio-p volumio[1195]: info: MRS: Pushing multiroomSync output Sep 08 21:29:57 volumio-p volumio[1195]: info: CoreCommandRouter::volumioGetState Sep 08 21:29:57 volumio-p volumio[1195]: info: CoreStateMachine::stPlaybackTimer Sep 08 21:29:57 volumio-p volumio[1195]: info: ControllerMpd::pushState Sep 08 21:29:57 volumio-p volumio[1195]: info: CoreCommandRouter::servicePushState Sep 08 21:29:57 volumio-p volumio[1195]: info: CorePlayQueue::getTrack 0 Sep 08 21:29:57 volumio-p volumio[1195]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":5353,"duration":238,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"585 Kbps","isStreaming":false,"title":"52027341&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/52027341&soundQuality=LOSSLESS","trackType":"tidal"} Sep 08 21:29:57 volumio-p volumio[1195]: verbose: CURRENT POSITION 0 Sep 08 21:29:57 volumio-p volumio[1195]: info: CoreStateMachine::syncState stateService pause Sep 08 21:29:57 volumio-p volumio[1195]: info: CoreStateMachine::syncState currentStatus pause Sep 08 21:29:57 volumio-p volumio[1195]: info: CoreStateMachine::pushState Sep 08 21:29:57 volumio-p volumio[1195]: info: CoreCommandRouter::volumioPushState Sep 08 21:29:57 volumio-p volumio[1195]: info: MRS: Pushing multiroomSync output update for this device Sep 08 21:29:57 volumio-p volumio[1195]: info: MRS: Pushing multiroomSync output Sep 08 21:29:57 volumio-p volumio[1195]: info: CoreCommandRouter::volumioGetState Sep 08 21:29:57 volumio-p volumio[1195]: info: CoreStateMachine::stPlaybackTimer Sep 08 21:29:57 volumio-p volumio[1195]: info: ControllerMpd::pushState Sep 08 21:29:57 volumio-p volumio[1195]: info: CoreCommandRouter::servicePushState Sep 08 21:29:57 volumio-p volumio[1195]: info: CorePlayQueue::getTrack 0 Sep 08 21:29:57 volumio-p volumio[1195]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":5353,"duration":238,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"585 Kbps","isStreaming":false,"title":"52027341&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/52027341&soundQuality=LOSSLESS","trackType":"tidal"} Sep 08 21:29:57 volumio-p volumio[1195]: verbose: CURRENT POSITION 0 Sep 08 21:29:57 volumio-p volumio[1195]: info: CoreStateMachine::syncState stateService pause Sep 08 21:29:57 volumio-p volumio[1195]: info: CoreStateMachine::syncState currentStatus pause Sep 08 21:29:57 volumio-p volumio[1195]: info: CoreStateMachine::pushState Sep 08 21:29:57 volumio-p volumio[1195]: info: CoreCommandRouter::volumioPushState Sep 08 21:29:57 volumio-p volumio[1195]: info: MRS: Pushing multiroomSync output update for this device Sep 08 21:29:57 volumio-p volumio[1195]: info: MRS: Pushing multiroomSync output Sep 08 21:29:57 volumio-p volumio[1195]: info: CoreCommandRouter::volumioGetState Sep 08 21:29:57 volumio-p volumio[1195]: info: CoreStateMachine::stPlaybackTimer Sep 08 21:29:57 volumio-p volumio[1195]: info: ------------------------------ 36ms Sep 08 21:29:57 volumio-p volumio[1195]: info: ------------------------------ 35ms Sep 08 21:29:57 volumio-p volumio[1195]: info: ------------------------------ 35ms Sep 08 21:30:08 volumio-p volumio[1195]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 08 21:30:08 volumio-p volumio[1195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 08 21:30:08 volumio-p volumio[1195]: info: Discovery: Getting this device information Sep 08 21:30:08 volumio-p volumio[1195]: info: CoreCommandRouter::volumioGetState Sep 08 21:30:08 volumio-p volumio[1195]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 08 21:30:12 volumio-p volumio[1195]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 08 21:30:12 volumio-p volumio[1195]: info: Received Get System Info Sep 08 21:30:12 volumio-p volumio[1195]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 08 21:30:12 volumio-p volumio[1195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 08 21:30:12 volumio-p volumio[1195]: info: Discovery: Getting this device information Sep 08 21:30:12 volumio-p volumio[1195]: info: CoreCommandRouter::volumioGetState Sep 08 21:30:12 volumio-p volumio[1195]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 08 21:30:13 volumio-p volumiologrotate[610]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Sep 08 21:30:13 volumio-p volumiologrotate[610]: ls: cannot access 'P': No such file or directory Sep 08 21:30:15 volumio-p volumio[1195]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 08 21:30:15 volumio-p volumio[1195]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 08 21:30:15 volumio-p volumio[1195]: info: Retrieving Cloud Streaming UI Sep 08 21:30:15 volumio-p volumio[1195]: info: Getting Tidal Cloud Configuration Sep 08 21:30:15 volumio-p volumio[1195]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 08 21:30:15 volumio-p volumio[1195]: info: Getting Qobuz Cloud Configuration Sep 08 21:30:15 volumio-p volumio[1195]: info: Asking plugin for UI Config Sep 08 21:30:15 volumio-p volumio[1195]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 08 21:30:15 volumio-p volumio[1195]: info: Getting Spotify Cloud Configuration Sep 08 21:30:15 volumio-p volumio[1195]: info: Asking plugin for UI Config Sep 08 21:30:15 volumio-p volumio[1195]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 08 21:30:15 volumio-p volumio[1195]: info: Saving Spotify Acccount Sep 08 21:30:15 volumio-p volumio[1195]: info: Got it Sep 08 21:30:15 volumio-p volumio[1195]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Sep 08 21:30:15 volumio-p volumio[1195]: info: Got Tidal Cloud Configuration Sep 08 21:30:15 volumio-p volumio[1195]: info: Got it Sep 08 21:30:15 volumio-p volumio[1195]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 08 21:30:15 volumio-p volumio[1195]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 08 21:30:15 volumio-p volumio[1195]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 08 21:30:15 volumio-p volumio[1195]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 08 21:30:15 volumio-p volumio[1195]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 08 21:30:15 volumio-p volumio[1195]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 08 21:30:15 volumio-p volumio[1195]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 08 21:30:15 volumio-p volumio[1195]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 08 21:30:15 volumio-p volumio[1195]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 08 21:30:15 volumio-p volumio[1195]: info: CoreCommandRouter::volumioGetBrowseSources Sep 08 21:30:15 volumio-p volumio[1195]: info: CoreCommandRouter::volumioGetBrowseSources Sep 08 21:30:15 volumio-p volumio[1195]: info: CoreCommandRouter::volumioGetBrowseSources Sep 08 21:30:15 volumio-p volumio[1195]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 08 21:30:15 volumio-p volumio[1195]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Sep 08 21:30:19 volumio-p volumio[1195]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 08 21:30:23 volumio-p volumio[1195]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 08 21:30:25 volumio-p volumio[1195]: info: Disabling MyMusic plugin upnp Sep 08 21:30:25 volumio-p sudo[2831]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Sep 08 21:30:25 volumio-p sudo[2831]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 21:30:25 volumio-p systemd[1]: Stopping UPnP Renderer front-end to MPD... Sep 08 21:30:25 volumio-p volumio[1195]: error: Upnp client error: Error: This socket has been ended by the other party Sep 08 21:30:25 volumio-p volumio[1195]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 08 21:30:26 volumio-p volumio[1195]: info: Enabling MyMusic plugin upnp Sep 08 21:30:26 volumio-p volumio[1195]: info: Enabling plugin upnp Sep 08 21:30:26 volumio-p volumio[1195]: info: Loading plugin "upnp"... Sep 08 21:30:26 volumio-p volumio[1195]: info: [1757356226298] Starting Upmpd Daemon Sep 08 21:30:26 volumio-p volumio[1195]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 08 21:30:26 volumio-p volumio[1195]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 08 21:30:26 volumio-p volumio[1195]: Error: listen EADDRINUSE: address already in use :::6599 Sep 08 21:30:26 volumio-p volumio[1195]: at Server.setupListenHandle [as _listen2] (net.js:1318:16) Sep 08 21:30:26 volumio-p volumio[1195]: at listenInCluster (net.js:1366:12) Sep 08 21:30:26 volumio-p volumio[1195]: at Server.listen (net.js:1452:7) Sep 08 21:30:26 volumio-p volumio[1195]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Sep 08 21:30:26 volumio-p volumio[1195]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Sep 08 21:30:26 volumio-p volumio[1195]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Sep 08 21:30:26 volumio-p volumio[1195]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Sep 08 21:30:26 volumio-p volumio[1195]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Sep 08 21:30:26 volumio-p volumio[1195]: code: 'EADDRINUSE', Sep 08 21:30:26 volumio-p volumio[1195]: errno: -98, Sep 08 21:30:26 volumio-p volumio[1195]: syscall: 'listen', Sep 08 21:30:26 volumio-p volumio[1195]: address: '::', Sep 08 21:30:26 volumio-p volumio[1195]: port: 6599 Sep 08 21:30:26 volumio-p volumio[1195]: } Sep 08 21:30:26 volumio-p volumio[1195]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 08 21:30:26 volumio-p sudo[2842]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-08 21:29 Sep 08 21:30:26 volumio-p sudo[2842]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"