-- Logs begin at Thu 2019-02-14 11:12:00 CET, end at Mon 2025-04-28 08:08:33 CEST. -- Apr 28 08:07:02 volumiox volumio[1080]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 28 08:07:06 volumiox volumio[1080]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 28 08:07:10 volumiox volumio[1080]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 28 08:07:14 volumiox volumio[1080]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 28 08:07:19 volumiox volumio[1080]: info: CoreCommandRouter::volumioGetState Apr 28 08:07:21 volumiox volumio[1080]: info: Executing endpoint metavolumio Apr 28 08:07:21 volumiox volumio[1080]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 28 08:07:21 volumiox volumio[1080]: info: Executing endpoint metavolumio Apr 28 08:07:21 volumiox volumio[1080]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 28 08:07:21 volumiox volumio[1080]: info: Executing endpoint metavolumio Apr 28 08:07:21 volumiox volumio[1080]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 28 08:07:23 volumiox volumio[1080]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Apr 28 08:07:23 volumiox volumio[1080]: info: browseTIDALUri took 257 milliseconds Apr 28 08:07:23 volumiox volumio[1080]: info: Preload queue cleared Apr 28 08:07:23 volumiox volumio[1080]: info: Preload queue cleared Apr 28 08:07:26 volumiox volumio[1080]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Apr 28 08:07:27 volumiox volumio[1080]: info: browseTIDALUri took 816 milliseconds Apr 28 08:07:27 volumiox volumio[1080]: info: Preload queue cleared Apr 28 08:07:27 volumiox volumio[1080]: info: Preload queue cleared Apr 28 08:07:29 volumiox volumio[1080]: info: Preload queue cleared Apr 28 08:07:29 volumiox volumio[1080]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 28 08:07:29 volumiox volumio[1080]: info: CoreStateMachine::ClearQueue Apr 28 08:07:29 volumiox volumio[1080]: info: CoreStateMachine::stop Apr 28 08:07:29 volumiox volumio[1080]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 28 08:07:29 volumiox volumio[1080]: info: CoreStateMachine::stPlaybackTimer Apr 28 08:07:29 volumiox volumio[1080]: info: CoreStateMachine::updateTrackBlock Apr 28 08:07:29 volumiox volumio[1080]: info: CorePlayQueue::getTrackBlock Apr 28 08:07:29 volumiox volumio[1080]: info: CoreStateMachine::pushState Apr 28 08:07:29 volumiox volumio[1080]: info: CorePlayQueue::getTrack 1 Apr 28 08:07:29 volumiox volumio[1080]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 08:07:29 volumiox volumio[1080]: info: CoreCommandRouter::volumioPushState Apr 28 08:07:29 volumiox volumio[1080]: info: CoreStateMachine::serviceStop Apr 28 08:07:29 volumiox volumio[1080]: info: CorePlayQueue::getTrack 1 Apr 28 08:07:29 volumiox volumio[1080]: info: CoreCommandRouter::serviceStop Apr 28 08:07:29 volumiox volumio[1080]: info: [1745820449934] ControllerTidal::stop Apr 28 08:07:29 volumiox volumio[1080]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 28 08:07:29 volumiox volumio[1080]: info: ControllerMpd::stop Apr 28 08:07:29 volumiox volumio[1080]: verbose: ControllerMpd::sendMpdCommand stop Apr 28 08:07:29 volumiox volumio[1080]: info: CorePlayQueue::clearPlayQueue Apr 28 08:07:29 volumiox volumio[1080]: info: CorePlayQueue::saveQueue Apr 28 08:07:29 volumiox volumio[1080]: info: CoreCommandRouter::volumioPushQueue Apr 28 08:07:29 volumiox volumio[1080]: info: CoreStateMachine::addQueueItems Apr 28 08:07:29 volumiox volumio[1080]: info: CorePlayQueue::addQueueItems Apr 28 08:07:29 volumiox volumio[1080]: info: Preload queue cleared Apr 28 08:07:29 volumiox volumio[1080]: info: Adding Item to queue: tidal://mymusic/albums/az/293800395 Apr 28 08:07:29 volumiox volumio[1080]: info: Exploding uri tidal://mymusic/albums/az/293800395 in service tidal Apr 28 08:07:29 volumiox volumio[1080]: info: Apr 28 08:07:29 volumiox volumio[1080]: ---------------------------- MPD announces state update: player Apr 28 08:07:29 volumiox volumio[1080]: info: sendMpdCommand stop took 46 milliseconds Apr 28 08:07:29 volumiox volumio[1080]: info: ControllerMpd::getState Apr 28 08:07:29 volumiox volumio[1080]: verbose: ControllerMpd::sendMpdCommand status Apr 28 08:07:29 volumiox volumio[1080]: info: sendMpdCommand status took 1 milliseconds Apr 28 08:07:29 volumiox volumio[1080]: verbose: ControllerMpd::parseState Apr 28 08:07:29 volumiox volumio[1080]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 28 08:07:30 volumiox volumio[1080]: info: sendMpdCommand playlistinfo took 36 milliseconds Apr 28 08:07:30 volumiox volumio[1080]: verbose: ControllerMpd::parseTrackInfo Apr 28 08:07:30 volumiox volumio[1080]: info: ControllerMpd::pushState Apr 28 08:07:30 volumiox volumio[1080]: info: CoreCommandRouter::servicePushState Apr 28 08:07:30 volumiox volumio[1080]: info: CoreStateMachine::pushState Apr 28 08:07:30 volumiox volumio[1080]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 08:07:30 volumiox volumio[1080]: info: CoreCommandRouter::volumioPushState Apr 28 08:07:30 volumiox volumio[1080]: info: CorePlayQueue::getTrack 1 Apr 28 08:07:30 volumiox volumio[1080]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"249551734&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/249551734&soundQuality=LOSSLESS","trackType":"tidal"} Apr 28 08:07:30 volumiox volumio[1080]: verbose: CURRENT POSITION 1 Apr 28 08:07:30 volumiox volumio[1080]: info: CoreStateMachine::syncState stateService stop Apr 28 08:07:30 volumiox volumio[1080]: info: CoreStateMachine::syncState currentStatus stop Apr 28 08:07:30 volumiox volumio[1080]: info: CoreStateMachine::pushState Apr 28 08:07:30 volumiox volumio[1080]: info: CoreCommandRouter::volumioPushState Apr 28 08:07:30 volumiox volumio[1080]: info: No code Apr 28 08:07:30 volumiox volumio[1080]: info: CoreStateMachine::pushState Apr 28 08:07:30 volumiox volumio[1080]: info: CoreCommandRouter::volumioPushState Apr 28 08:07:30 volumiox volumio[1080]: info: ------------------------------ 102ms Apr 28 08:07:30 volumiox volumio[1080]: info: touch_display: Setting screensaver timeout to 120 seconds. Apr 28 08:07:30 volumiox volumio[1080]: info: explodeTIDALUri took 584 milliseconds Apr 28 08:07:30 volumiox volumio[1080]: info: CoreCommandRouter::volumioPushQueue Apr 28 08:07:30 volumiox volumio[1080]: info: CorePlayQueue::saveQueue Apr 28 08:07:30 volumiox volumio[1080]: info: CoreStateMachine::updateTrackBlock Apr 28 08:07:30 volumiox volumio[1080]: info: CorePlayQueue::getTrackBlock Apr 28 08:07:30 volumiox volumio[1080]: info: CoreCommandRouter::volumioPlay Apr 28 08:07:30 volumiox volumio[1080]: info: CoreStateMachine::play index 0 Apr 28 08:07:30 volumiox volumio[1080]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 28 08:07:30 volumiox volumio[1080]: info: CoreStateMachine::stop Apr 28 08:07:30 volumiox volumio[1080]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 28 08:07:30 volumiox volumio[1080]: info: CoreStateMachine::play index undefined Apr 28 08:07:30 volumiox volumio[1080]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 28 08:07:30 volumiox volumio[1080]: info: CorePlayQueue::getTrack 0 Apr 28 08:07:30 volumiox volumio[1080]: info: CoreStateMachine::startPlaybackTimer Apr 28 08:07:30 volumiox volumio[1080]: info: CorePlayQueue::getTrack 0 Apr 28 08:07:30 volumiox volumio[1080]: info: [1745820450536] ControllerTidal::clearAddPlayTrack Apr 28 08:07:30 volumiox volumio[1080]: info: Getting stream with soundQuality HI_RES Apr 28 08:07:30 volumiox volumio[1080]: verbose: ControllerMpd::sendMpdCommand stop Apr 28 08:07:30 volumiox volumio[1080]: info: sendMpdCommand stop took 3 milliseconds Apr 28 08:07:30 volumiox volumio[1080]: verbose: ControllerMpd::sendMpdCommand clear Apr 28 08:07:30 volumiox volumio[1080]: info: Apr 28 08:07:30 volumiox volumio[1080]: ---------------------------- MPD announces system playlist update Apr 28 08:07:30 volumiox volumio[1080]: info: Ignoring MPD Status Update Apr 28 08:07:30 volumiox volumio[1080]: info: sendMpdCommand clear took 5 milliseconds Apr 28 08:07:30 volumiox volumio[1080]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/293800396&soundQuality=HI_RES" Apr 28 08:07:30 volumiox volumio[1080]: error: updateQueue error: null Apr 28 08:07:30 volumiox volumio[1080]: info: Apr 28 08:07:30 volumiox volumio[1080]: ---------------------------- MPD announces system playlist update Apr 28 08:07:30 volumiox volumio[1080]: info: Ignoring MPD Status Update Apr 28 08:07:30 volumiox volumio[1080]: info: ------------------------------ 3ms Apr 28 08:07:30 volumiox volumio[1080]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/293800396&soundQuality=HI_RES" took 2 milliseconds Apr 28 08:07:30 volumiox volumio[1080]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 28 08:07:30 volumiox volumio[1080]: verbose: ControllerMpd::sendMpdCommand play Apr 28 08:07:30 volumiox volumio[1080]: STREAMING PROXY: Handling url /?data=tidal://song/293800396&soundQuality=HI_RES Apr 28 08:07:30 volumiox volumio[1080]: info: ------------------------------ 6ms Apr 28 08:07:30 volumiox volumio[1080]: info: sendMpdCommand play took 5 milliseconds Apr 28 08:07:30 volumiox volumio[1080]: info: Executing endpoint getStreamUrltidal Apr 28 08:07:30 volumiox volumio[1080]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Apr 28 08:07:31 volumiox volumio[1080]: info: getStreamUrl took 507 milliseconds Apr 28 08:07:31 volumiox volumio[1080]: STREAMING PROXY: Got real url: http://sp-pr-cf.audio.tidal.com/mediatracks/CAEaKwgDEic5YTNjMDAyMmQ3ZTRmZDI5ZWQ4ZGU1YjExNzRhMzAwOV82MS5tcDQ/0.flac?Expires=1745824051&Signature=dltazLHh6MBAdZvvcdg8B23LVmIDmA--ZgVlgtveHOLZ8xdfW7O~TFcEZGGjjOvxUA0~4xCouop4O3C3Y7xshlAXKf8HHrmJGvx2-WO9xkXiFix7fVFo0AsY4esx2EYcoXitwAn5Ya0x0hPpwANbBumL~5RsWaXHrGmJdaRoTpa7w0JtISZ0J0YNGlkq6Ya11epfFgSdwBob7RGo2eD~TGPMewg3tbUTDhcEjSkeSgwDk4NUEru9t3tQDu6CJ-CzGnryJ0s7psWqaDFMBMEckcn8XyehWanKIJe8Rfm5pTsFlkvynPQnVCg6TVGYk4MgNCm7eArvOlUHoKzJe-q9pw__&Key-Pair-Id=K14LZCZ9QUI4JL Apr 28 08:07:31 volumiox volumio[1080]: STREAMING PROXY: Response: 200, length: 23415146 Apr 28 08:07:31 volumiox volumio[1080]: info: Apr 28 08:07:31 volumiox volumio[1080]: ---------------------------- MPD announces state update: player Apr 28 08:07:31 volumiox volumio[1080]: info: ControllerMpd::getState Apr 28 08:07:31 volumiox volumio[1080]: verbose: ControllerMpd::sendMpdCommand status Apr 28 08:07:31 volumiox kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Apr 28 08:07:31 volumiox volumio[1080]: info: sendMpdCommand status took 30 milliseconds Apr 28 08:07:31 volumiox volumio[1080]: verbose: ControllerMpd::parseState Apr 28 08:07:31 volumiox volumio[1080]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 28 08:07:31 volumiox volumio[1080]: info: Apr 28 08:07:31 volumiox volumio[1080]: ---------------------------- MPD announces state update: player Apr 28 08:07:31 volumiox volumio[1080]: info: ControllerMpd::getState Apr 28 08:07:31 volumiox volumio[1080]: verbose: ControllerMpd::sendMpdCommand status Apr 28 08:07:31 volumiox volumio[1080]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 28 08:07:31 volumiox volumio[1080]: verbose: ControllerMpd::parseTrackInfo Apr 28 08:07:31 volumiox volumio[1080]: info: ControllerMpd::pushState Apr 28 08:07:31 volumiox volumio[1080]: info: CoreCommandRouter::servicePushState Apr 28 08:07:31 volumiox volumio[1080]: info: CorePlayQueue::getTrack 0 Apr 28 08:07:31 volumiox volumio[1080]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":320,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"293800396&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/293800396&soundQuality=HI_RES","trackType":"tidal"} Apr 28 08:07:31 volumiox volumio[1080]: verbose: CURRENT POSITION 0 Apr 28 08:07:31 volumiox volumio[1080]: info: CoreStateMachine::syncState stateService play Apr 28 08:07:31 volumiox volumio[1080]: info: CoreStateMachine::syncState currentStatus stop Apr 28 08:07:31 volumiox volumio[1080]: info: ------------------------------ 36ms Apr 28 08:07:31 volumiox volumio[1080]: info: sendMpdCommand status took 3 milliseconds Apr 28 08:07:31 volumiox volumio[1080]: verbose: ControllerMpd::parseState Apr 28 08:07:31 volumiox volumio[1080]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 28 08:07:31 volumiox volumio[1080]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 28 08:07:31 volumiox volumio[1080]: verbose: ControllerMpd::parseTrackInfo Apr 28 08:07:31 volumiox volumio[1080]: info: ControllerMpd::pushState Apr 28 08:07:31 volumiox volumio[1080]: info: CoreCommandRouter::servicePushState Apr 28 08:07:31 volumiox volumio[1080]: info: CorePlayQueue::getTrack 0 Apr 28 08:07:31 volumiox volumio[1080]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":320,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"293800396&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/293800396&soundQuality=HI_RES","trackType":"tidal"} Apr 28 08:07:31 volumiox volumio[1080]: verbose: CURRENT POSITION 0 Apr 28 08:07:31 volumiox volumio[1080]: info: CoreStateMachine::syncState stateService play Apr 28 08:07:31 volumiox volumio[1080]: info: CoreStateMachine::syncState currentStatus play Apr 28 08:07:31 volumiox volumio[1080]: info: Received an update from plugin. extracting info from payload Apr 28 08:07:31 volumiox volumio[1080]: info: CoreStateMachine::pushState Apr 28 08:07:31 volumiox volumio[1080]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 08:07:31 volumiox volumio[1080]: info: CoreCommandRouter::volumioPushState Apr 28 08:07:31 volumiox volumio[1080]: info: CoreStateMachine::pushState Apr 28 08:07:31 volumiox volumio[1080]: info: CoreCommandRouter::volumioPushState Apr 28 08:07:31 volumiox volumio[1080]: info: ------------------------------ 47ms Apr 28 08:07:33 volumiox volumio[1080]: STREAMING PROXY: Client dropped request, destroying Apr 28 08:07:41 volumiox volumio[1080]: info: Preload queue cleared Apr 28 08:07:41 volumiox volumio[1080]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 28 08:07:41 volumiox volumio[1080]: info: CoreStateMachine::ClearQueue Apr 28 08:07:41 volumiox volumio[1080]: info: CoreStateMachine::stop Apr 28 08:07:41 volumiox volumio[1080]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 28 08:07:41 volumiox volumio[1080]: info: CoreStateMachine::stPlaybackTimer Apr 28 08:07:41 volumiox volumio[1080]: info: CoreStateMachine::updateTrackBlock Apr 28 08:07:41 volumiox volumio[1080]: info: CorePlayQueue::getTrackBlock Apr 28 08:07:41 volumiox volumio[1080]: info: CoreStateMachine::pushState Apr 28 08:07:41 volumiox volumio[1080]: info: CorePlayQueue::getTrack 0 Apr 28 08:07:41 volumiox volumio[1080]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 08:07:41 volumiox volumio[1080]: info: CoreCommandRouter::volumioPushState Apr 28 08:07:41 volumiox volumio[1080]: info: CoreStateMachine::serviceStop Apr 28 08:07:41 volumiox volumio[1080]: info: CorePlayQueue::getTrack 0 Apr 28 08:07:41 volumiox volumio[1080]: info: CoreCommandRouter::serviceStop Apr 28 08:07:41 volumiox volumio[1080]: info: [1745820461939] ControllerTidal::stop Apr 28 08:07:41 volumiox volumio[1080]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 28 08:07:41 volumiox volumio[1080]: info: ControllerMpd::stop Apr 28 08:07:41 volumiox volumio[1080]: verbose: ControllerMpd::sendMpdCommand stop Apr 28 08:07:41 volumiox volumio[1080]: info: CorePlayQueue::clearPlayQueue Apr 28 08:07:41 volumiox volumio[1080]: info: CorePlayQueue::saveQueue Apr 28 08:07:41 volumiox volumio[1080]: info: CoreCommandRouter::volumioPushQueue Apr 28 08:07:41 volumiox volumio[1080]: info: CoreStateMachine::addQueueItems Apr 28 08:07:41 volumiox volumio[1080]: info: CorePlayQueue::addQueueItems Apr 28 08:07:41 volumiox volumio[1080]: info: Preload queue cleared Apr 28 08:07:41 volumiox volumio[1080]: info: Adding Item to queue: tidal://mymusic/albums/az/288858858 Apr 28 08:07:41 volumiox volumio[1080]: info: Exploding uri tidal://mymusic/albums/az/288858858 in service tidal Apr 28 08:07:41 volumiox volumio[1080]: info: Apr 28 08:07:41 volumiox volumio[1080]: ---------------------------- MPD announces state update: player Apr 28 08:07:41 volumiox volumio[1080]: info: sendMpdCommand stop took 57 milliseconds Apr 28 08:07:41 volumiox volumio[1080]: info: ControllerMpd::getState Apr 28 08:07:41 volumiox volumio[1080]: verbose: ControllerMpd::sendMpdCommand status Apr 28 08:07:42 volumiox volumio[1080]: info: sendMpdCommand status took 33 milliseconds Apr 28 08:07:42 volumiox volumio[1080]: verbose: ControllerMpd::parseState Apr 28 08:07:42 volumiox volumio[1080]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 28 08:07:42 volumiox volumio[1080]: info: sendMpdCommand playlistinfo took 11 milliseconds Apr 28 08:07:42 volumiox volumio[1080]: verbose: ControllerMpd::parseTrackInfo Apr 28 08:07:42 volumiox volumio[1080]: info: ControllerMpd::pushState Apr 28 08:07:42 volumiox volumio[1080]: info: CoreCommandRouter::servicePushState Apr 28 08:07:42 volumiox volumio[1080]: info: CoreStateMachine::pushState Apr 28 08:07:42 volumiox volumio[1080]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 08:07:42 volumiox volumio[1080]: info: CoreCommandRouter::volumioPushState Apr 28 08:07:42 volumiox volumio[1080]: info: CorePlayQueue::getTrack 0 Apr 28 08:07:42 volumiox volumio[1080]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"293800396&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/293800396&soundQuality=HI_RES","trackType":"tidal"} Apr 28 08:07:42 volumiox volumio[1080]: verbose: CURRENT POSITION 0 Apr 28 08:07:42 volumiox volumio[1080]: info: CoreStateMachine::syncState stateService stop Apr 28 08:07:42 volumiox volumio[1080]: info: CoreStateMachine::syncState currentStatus stop Apr 28 08:07:42 volumiox volumio[1080]: info: CoreStateMachine::pushState Apr 28 08:07:42 volumiox volumio[1080]: info: CoreCommandRouter::volumioPushState Apr 28 08:07:42 volumiox volumio[1080]: info: No code Apr 28 08:07:42 volumiox volumio[1080]: info: CoreStateMachine::pushState Apr 28 08:07:42 volumiox volumio[1080]: info: CoreCommandRouter::volumioPushState Apr 28 08:07:42 volumiox volumio[1080]: info: ------------------------------ 113ms Apr 28 08:07:42 volumiox volumio[1080]: info: touch_display: Setting screensaver timeout to 120 seconds. Apr 28 08:07:42 volumiox volumio[1080]: info: explodeTIDALUri took 513 milliseconds Apr 28 08:07:42 volumiox volumio[1080]: info: CoreCommandRouter::volumioPushQueue Apr 28 08:07:42 volumiox volumio[1080]: info: CorePlayQueue::saveQueue Apr 28 08:07:42 volumiox volumio[1080]: info: CoreStateMachine::updateTrackBlock Apr 28 08:07:42 volumiox volumio[1080]: info: CorePlayQueue::getTrackBlock Apr 28 08:07:42 volumiox volumio[1080]: info: CoreCommandRouter::volumioPlay Apr 28 08:07:42 volumiox volumio[1080]: info: CoreStateMachine::play index 0 Apr 28 08:07:42 volumiox volumio[1080]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 28 08:07:42 volumiox volumio[1080]: info: CoreStateMachine::stop Apr 28 08:07:42 volumiox volumio[1080]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 28 08:07:42 volumiox volumio[1080]: info: CoreStateMachine::play index undefined Apr 28 08:07:42 volumiox volumio[1080]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 28 08:07:42 volumiox volumio[1080]: info: CorePlayQueue::getTrack 0 Apr 28 08:07:42 volumiox volumio[1080]: info: CoreStateMachine::startPlaybackTimer Apr 28 08:07:42 volumiox volumio[1080]: info: CorePlayQueue::getTrack 0 Apr 28 08:07:42 volumiox volumio[1080]: info: [1745820462463] ControllerTidal::clearAddPlayTrack Apr 28 08:07:42 volumiox volumio[1080]: info: Getting stream with soundQuality HI_RES Apr 28 08:07:42 volumiox volumio[1080]: verbose: ControllerMpd::sendMpdCommand stop Apr 28 08:07:42 volumiox volumio[1080]: info: sendMpdCommand stop took 2 milliseconds Apr 28 08:07:42 volumiox volumio[1080]: verbose: ControllerMpd::sendMpdCommand clear Apr 28 08:07:42 volumiox volumio[1080]: info: Apr 28 08:07:42 volumiox volumio[1080]: ---------------------------- MPD announces system playlist update Apr 28 08:07:42 volumiox volumio[1080]: info: Ignoring MPD Status Update Apr 28 08:07:42 volumiox volumio[1080]: info: sendMpdCommand clear took 6 milliseconds Apr 28 08:07:42 volumiox volumio[1080]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/288858869&soundQuality=HI_RES" Apr 28 08:07:42 volumiox volumio[1080]: error: updateQueue error: null Apr 28 08:07:42 volumiox volumio[1080]: info: Apr 28 08:07:42 volumiox volumio[1080]: ---------------------------- MPD announces system playlist update Apr 28 08:07:42 volumiox volumio[1080]: info: Ignoring MPD Status Update Apr 28 08:07:42 volumiox volumio[1080]: info: ------------------------------ 3ms Apr 28 08:07:42 volumiox volumio[1080]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/288858869&soundQuality=HI_RES" took 2 milliseconds Apr 28 08:07:42 volumiox volumio[1080]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 28 08:07:42 volumiox volumio[1080]: verbose: ControllerMpd::sendMpdCommand play Apr 28 08:07:42 volumiox volumio[1080]: STREAMING PROXY: Handling url /?data=tidal://song/288858869&soundQuality=HI_RES Apr 28 08:07:42 volumiox volumio[1080]: info: ------------------------------ 5ms Apr 28 08:07:42 volumiox volumio[1080]: info: sendMpdCommand play took 5 milliseconds Apr 28 08:07:42 volumiox volumio[1080]: info: Executing endpoint getStreamUrltidal Apr 28 08:07:42 volumiox volumio[1080]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Apr 28 08:07:43 volumiox volumio[1080]: info: getStreamUrl took 692 milliseconds Apr 28 08:07:43 volumiox volumio[1080]: STREAMING PROXY: Got real url: http://sp-pr-cf.audio.tidal.com/mediatracks/CAEaKwgDEiczMzkzYzc5ZGE5Y2RiMjY2Yjk4MGJkNDY5MGIzYmE3NF82MS5tcDQ/0.flac?Expires=1745824063&Signature=QgVXsbpPuM08dNAyMGznzC3x~vwmonua34gZoj35yPo7hmL3dHPYYct2jaB3Ar3EjdZuljAws4TGlYZlIB2DXFGOJYxg2-Jjm7dNUmKm46~tRIw~lXbRyPEzfJu5KyFVZ0v7qiHDryy8E~qJMYaJ2cRrrT5JNoqpPPgsqweRkzE0hvGTD5lZRfp37iMC0IZy75tMWAOxFlbjwn-jOMTspPv55t1TSBMQnLRM14CM5q5MY3dPr9A1BQQfgdhYyTQCINMu8Pf4zaJP8WNbP3jVynjTLcuuZoplAvIWr8Sf5ac3xH01jscRPnfTuMqncLwcUO-nyssGMGcdvr6RRXLd3A__&Key-Pair-Id=K14LZCZ9QUI4JL Apr 28 08:07:43 volumiox volumio[1080]: STREAMING PROXY: Response: 200, length: 12301309 Apr 28 08:07:43 volumiox volumio[1080]: info: Apr 28 08:07:43 volumiox volumio[1080]: ---------------------------- MPD announces state update: player Apr 28 08:07:43 volumiox volumio[1080]: info: ControllerMpd::getState Apr 28 08:07:43 volumiox volumio[1080]: verbose: ControllerMpd::sendMpdCommand status Apr 28 08:07:43 volumiox kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Apr 28 08:07:43 volumiox volumio[1080]: info: Apr 28 08:07:43 volumiox volumio[1080]: ---------------------------- MPD announces state update: player Apr 28 08:07:43 volumiox volumio[1080]: info: sendMpdCommand status took 31 milliseconds Apr 28 08:07:43 volumiox volumio[1080]: info: ControllerMpd::getState Apr 28 08:07:43 volumiox volumio[1080]: verbose: ControllerMpd::sendMpdCommand status Apr 28 08:07:43 volumiox volumio[1080]: verbose: ControllerMpd::parseState Apr 28 08:07:43 volumiox volumio[1080]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 28 08:07:43 volumiox volumio[1080]: info: sendMpdCommand status took 1 milliseconds Apr 28 08:07:43 volumiox volumio[1080]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 28 08:07:43 volumiox volumio[1080]: verbose: ControllerMpd::parseState Apr 28 08:07:43 volumiox volumio[1080]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 28 08:07:43 volumiox volumio[1080]: verbose: ControllerMpd::parseTrackInfo Apr 28 08:07:43 volumiox volumio[1080]: info: ControllerMpd::pushState Apr 28 08:07:43 volumiox volumio[1080]: info: CoreCommandRouter::servicePushState Apr 28 08:07:43 volumiox volumio[1080]: info: CorePlayQueue::getTrack 0 Apr 28 08:07:43 volumiox volumio[1080]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":145,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"288858869&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/288858869&soundQuality=HI_RES","trackType":"tidal"} Apr 28 08:07:43 volumiox volumio[1080]: verbose: CURRENT POSITION 0 Apr 28 08:07:43 volumiox volumio[1080]: info: CoreStateMachine::syncState stateService play Apr 28 08:07:43 volumiox volumio[1080]: info: CoreStateMachine::syncState currentStatus stop Apr 28 08:07:43 volumiox volumio[1080]: info: ------------------------------ 37ms Apr 28 08:07:43 volumiox volumio[1080]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 28 08:07:43 volumiox volumio[1080]: verbose: ControllerMpd::parseTrackInfo Apr 28 08:07:43 volumiox volumio[1080]: info: ControllerMpd::pushState Apr 28 08:07:43 volumiox volumio[1080]: info: CoreCommandRouter::servicePushState Apr 28 08:07:43 volumiox volumio[1080]: info: CorePlayQueue::getTrack 0 Apr 28 08:07:43 volumiox volumio[1080]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":145,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"288858869&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/288858869&soundQuality=HI_RES","trackType":"tidal"} Apr 28 08:07:43 volumiox volumio[1080]: verbose: CURRENT POSITION 0 Apr 28 08:07:43 volumiox volumio[1080]: info: CoreStateMachine::syncState stateService play Apr 28 08:07:43 volumiox volumio[1080]: info: CoreStateMachine::syncState currentStatus play Apr 28 08:07:43 volumiox volumio[1080]: info: Received an update from plugin. extracting info from payload Apr 28 08:07:43 volumiox volumio[1080]: info: CoreStateMachine::pushState Apr 28 08:07:43 volumiox volumio[1080]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 08:07:43 volumiox volumio[1080]: info: CoreCommandRouter::volumioPushState Apr 28 08:07:43 volumiox volumio[1080]: info: CoreStateMachine::pushState Apr 28 08:07:43 volumiox volumio[1080]: info: CoreCommandRouter::volumioPushState Apr 28 08:07:43 volumiox volumio[1080]: info: ------------------------------ 43ms Apr 28 08:07:45 volumiox volumio[1080]: STREAMING PROXY: Client dropped request, destroying Apr 28 08:07:47 volumiox volumio[1080]: info: Preload queue cleared Apr 28 08:07:47 volumiox volumio[1080]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 28 08:07:47 volumiox volumio[1080]: info: CoreStateMachine::ClearQueue Apr 28 08:07:47 volumiox volumio[1080]: info: CoreStateMachine::stop Apr 28 08:07:47 volumiox volumio[1080]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 28 08:07:47 volumiox volumio[1080]: info: CoreStateMachine::stPlaybackTimer Apr 28 08:07:47 volumiox volumio[1080]: info: CoreStateMachine::updateTrackBlock Apr 28 08:07:47 volumiox volumio[1080]: info: CorePlayQueue::getTrackBlock Apr 28 08:07:47 volumiox volumio[1080]: info: CoreStateMachine::pushState Apr 28 08:07:47 volumiox volumio[1080]: info: CorePlayQueue::getTrack 0 Apr 28 08:07:47 volumiox volumio[1080]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 08:07:47 volumiox volumio[1080]: info: CoreCommandRouter::volumioPushState Apr 28 08:07:47 volumiox volumio[1080]: info: CoreStateMachine::serviceStop Apr 28 08:07:47 volumiox volumio[1080]: info: CorePlayQueue::getTrack 0 Apr 28 08:07:47 volumiox volumio[1080]: info: CoreCommandRouter::serviceStop Apr 28 08:07:47 volumiox volumio[1080]: info: [1745820467580] ControllerTidal::stop Apr 28 08:07:47 volumiox volumio[1080]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 28 08:07:47 volumiox volumio[1080]: info: ControllerMpd::stop Apr 28 08:07:47 volumiox volumio[1080]: verbose: ControllerMpd::sendMpdCommand stop Apr 28 08:07:47 volumiox volumio[1080]: info: CorePlayQueue::clearPlayQueue Apr 28 08:07:47 volumiox volumio[1080]: info: CorePlayQueue::saveQueue Apr 28 08:07:47 volumiox volumio[1080]: info: CoreCommandRouter::volumioPushQueue Apr 28 08:07:47 volumiox volumio[1080]: info: CoreStateMachine::addQueueItems Apr 28 08:07:47 volumiox volumio[1080]: info: CorePlayQueue::addQueueItems Apr 28 08:07:47 volumiox volumio[1080]: info: Preload queue cleared Apr 28 08:07:47 volumiox volumio[1080]: info: Adding Item to queue: tidal://mymusic/albums/az/351933719 Apr 28 08:07:47 volumiox volumio[1080]: info: Exploding uri tidal://mymusic/albums/az/351933719 in service tidal Apr 28 08:07:47 volumiox volumio[1080]: info: Apr 28 08:07:47 volumiox volumio[1080]: ---------------------------- MPD announces state update: player Apr 28 08:07:47 volumiox volumio[1080]: info: sendMpdCommand stop took 44 milliseconds Apr 28 08:07:47 volumiox volumio[1080]: info: ControllerMpd::getState Apr 28 08:07:47 volumiox volumio[1080]: verbose: ControllerMpd::sendMpdCommand status Apr 28 08:07:47 volumiox volumio[1080]: info: sendMpdCommand status took 32 milliseconds Apr 28 08:07:47 volumiox volumio[1080]: verbose: ControllerMpd::parseState Apr 28 08:07:47 volumiox volumio[1080]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 28 08:07:47 volumiox volumio[1080]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 28 08:07:47 volumiox volumio[1080]: verbose: ControllerMpd::parseTrackInfo Apr 28 08:07:47 volumiox volumio[1080]: info: ControllerMpd::pushState Apr 28 08:07:47 volumiox volumio[1080]: info: CoreCommandRouter::servicePushState Apr 28 08:07:47 volumiox volumio[1080]: info: CoreStateMachine::pushState Apr 28 08:07:47 volumiox volumio[1080]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 08:07:47 volumiox volumio[1080]: info: CoreCommandRouter::volumioPushState Apr 28 08:07:47 volumiox volumio[1080]: info: CorePlayQueue::getTrack 0 Apr 28 08:07:47 volumiox volumio[1080]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"288858869&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/288858869&soundQuality=HI_RES","trackType":"tidal"} Apr 28 08:07:47 volumiox volumio[1080]: verbose: CURRENT POSITION 0 Apr 28 08:07:47 volumiox volumio[1080]: info: CoreStateMachine::syncState stateService stop Apr 28 08:07:47 volumiox volumio[1080]: info: CoreStateMachine::syncState currentStatus stop Apr 28 08:07:47 volumiox volumio[1080]: info: CoreStateMachine::pushState Apr 28 08:07:47 volumiox volumio[1080]: info: CoreCommandRouter::volumioPushState Apr 28 08:07:47 volumiox volumio[1080]: info: No code Apr 28 08:07:47 volumiox volumio[1080]: info: CoreStateMachine::pushState Apr 28 08:07:47 volumiox volumio[1080]: info: CoreCommandRouter::volumioPushState Apr 28 08:07:47 volumiox volumio[1080]: info: ------------------------------ 97ms Apr 28 08:07:47 volumiox volumio[1080]: info: touch_display: Setting screensaver timeout to 120 seconds. Apr 28 08:07:48 volumiox volumio[1080]: info: explodeTIDALUri took 510 milliseconds Apr 28 08:07:48 volumiox volumio[1080]: info: CoreCommandRouter::volumioPushQueue Apr 28 08:07:48 volumiox volumio[1080]: info: CorePlayQueue::saveQueue Apr 28 08:07:48 volumiox volumio[1080]: info: CoreStateMachine::updateTrackBlock Apr 28 08:07:48 volumiox volumio[1080]: info: CorePlayQueue::getTrackBlock Apr 28 08:07:48 volumiox volumio[1080]: info: CoreCommandRouter::volumioPlay Apr 28 08:07:48 volumiox volumio[1080]: info: CoreStateMachine::play index 0 Apr 28 08:07:48 volumiox volumio[1080]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 28 08:07:48 volumiox volumio[1080]: info: CoreStateMachine::stop Apr 28 08:07:48 volumiox volumio[1080]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 28 08:07:48 volumiox volumio[1080]: info: CoreStateMachine::play index undefined Apr 28 08:07:48 volumiox volumio[1080]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 28 08:07:48 volumiox volumio[1080]: info: CorePlayQueue::getTrack 0 Apr 28 08:07:48 volumiox volumio[1080]: info: CoreStateMachine::startPlaybackTimer Apr 28 08:07:48 volumiox volumio[1080]: info: CorePlayQueue::getTrack 0 Apr 28 08:07:48 volumiox volumio[1080]: info: [1745820468106] ControllerTidal::clearAddPlayTrack Apr 28 08:07:48 volumiox volumio[1080]: info: Getting stream with soundQuality HI_RES Apr 28 08:07:48 volumiox volumio[1080]: verbose: ControllerMpd::sendMpdCommand stop Apr 28 08:07:48 volumiox volumio[1080]: info: sendMpdCommand stop took 2 milliseconds Apr 28 08:07:48 volumiox volumio[1080]: verbose: ControllerMpd::sendMpdCommand clear Apr 28 08:07:48 volumiox volumio[1080]: info: Apr 28 08:07:48 volumiox volumio[1080]: ---------------------------- MPD announces system playlist update Apr 28 08:07:48 volumiox volumio[1080]: info: Ignoring MPD Status Update Apr 28 08:07:48 volumiox volumio[1080]: info: sendMpdCommand clear took 5 milliseconds Apr 28 08:07:48 volumiox volumio[1080]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/351933722&soundQuality=HI_RES" Apr 28 08:07:48 volumiox volumio[1080]: error: updateQueue error: null Apr 28 08:07:48 volumiox volumio[1080]: info: Apr 28 08:07:48 volumiox volumio[1080]: ---------------------------- MPD announces system playlist update Apr 28 08:07:48 volumiox volumio[1080]: info: Ignoring MPD Status Update Apr 28 08:07:48 volumiox volumio[1080]: info: ------------------------------ 3ms Apr 28 08:07:48 volumiox volumio[1080]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/351933722&soundQuality=HI_RES" took 2 milliseconds Apr 28 08:07:48 volumiox volumio[1080]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 28 08:07:48 volumiox volumio[1080]: verbose: ControllerMpd::sendMpdCommand play Apr 28 08:07:48 volumiox volumio[1080]: STREAMING PROXY: Handling url /?data=tidal://song/351933722&soundQuality=HI_RES Apr 28 08:07:48 volumiox volumio[1080]: info: ------------------------------ 6ms Apr 28 08:07:48 volumiox volumio[1080]: info: sendMpdCommand play took 5 milliseconds Apr 28 08:07:48 volumiox volumio[1080]: info: Executing endpoint getStreamUrltidal Apr 28 08:07:48 volumiox volumio[1080]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Apr 28 08:07:48 volumiox volumio[1080]: info: getStreamUrl took 567 milliseconds Apr 28 08:07:48 volumiox volumio[1080]: STREAMING PROXY: Got real url: http://sp-pr-cf.audio.tidal.com/mediatracks/CAEaKAgDEiRkMGI3ZTBhOTMwMWQ4NTEzYWIwYmVmMzQ0OWZkZDE1YS5tcDQ/0.flac?Expires=1745824068&Signature=pIvVqHYOsQ06-WKhSAlc488F-GbgG2u9xyPIGGDDD~XbfGhJeu~CO1c51m8mnElgSRIlDINAfT14wLZ2FPdq1paonze0LwI0lH1dKlTt7R~Vt1Uf-rgtd-Mb7lZwNE3ZtfHPwEH2v35BW9v~-03OVuAosKSMNb59KIKuKCF21O5Y5EQ6Fdu9hwSJLSHhuvOUE~sN41ihCkhWElTSOeyMaavA9O~MQsPdFXeE2FbMWX~gw0W7RM6yL3KfnWZaNqa4iUfeENYikBdgIOi8bb4hof1tgmM7iLe3WBdtgkCDhFm4uvGooFTwFOzFw0Z8pRWarjDipLCenSshx0udYw9O-A__&Key-Pair-Id=K14LZCZ9QUI4JL Apr 28 08:07:48 volumiox volumio[1080]: STREAMING PROXY: Response: 200, length: 40643264 Apr 28 08:07:48 volumiox volumio[1080]: info: Apr 28 08:07:48 volumiox volumio[1080]: ---------------------------- MPD announces state update: player Apr 28 08:07:48 volumiox volumio[1080]: info: ControllerMpd::getState Apr 28 08:07:48 volumiox volumio[1080]: verbose: ControllerMpd::sendMpdCommand status Apr 28 08:07:48 volumiox kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Apr 28 08:07:48 volumiox volumio[1080]: info: Apr 28 08:07:48 volumiox volumio[1080]: ---------------------------- MPD announces state update: player Apr 28 08:07:48 volumiox volumio[1080]: info: sendMpdCommand status took 32 milliseconds Apr 28 08:07:48 volumiox volumio[1080]: info: ControllerMpd::getState Apr 28 08:07:48 volumiox volumio[1080]: verbose: ControllerMpd::sendMpdCommand status Apr 28 08:07:48 volumiox volumio[1080]: verbose: ControllerMpd::parseState Apr 28 08:07:48 volumiox volumio[1080]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 28 08:07:48 volumiox volumio[1080]: info: sendMpdCommand status took 2 milliseconds Apr 28 08:07:48 volumiox volumio[1080]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 28 08:07:48 volumiox volumio[1080]: verbose: ControllerMpd::parseState Apr 28 08:07:48 volumiox volumio[1080]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 28 08:07:48 volumiox volumio[1080]: verbose: ControllerMpd::parseTrackInfo Apr 28 08:07:48 volumiox volumio[1080]: info: ControllerMpd::pushState Apr 28 08:07:48 volumiox volumio[1080]: info: CoreCommandRouter::servicePushState Apr 28 08:07:48 volumiox volumio[1080]: info: CorePlayQueue::getTrack 0 Apr 28 08:07:48 volumiox volumio[1080]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":359,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"351933722&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/351933722&soundQuality=HI_RES","trackType":"tidal"} Apr 28 08:07:48 volumiox volumio[1080]: verbose: CURRENT POSITION 0 Apr 28 08:07:48 volumiox volumio[1080]: info: CoreStateMachine::syncState stateService play Apr 28 08:07:48 volumiox volumio[1080]: info: CoreStateMachine::syncState currentStatus stop Apr 28 08:07:48 volumiox volumio[1080]: info: ------------------------------ 39ms Apr 28 08:07:48 volumiox volumio[1080]: info: sendMpdCommand playlistinfo took 7 milliseconds Apr 28 08:07:48 volumiox volumio[1080]: verbose: ControllerMpd::parseTrackInfo Apr 28 08:07:48 volumiox volumio[1080]: info: ControllerMpd::pushState Apr 28 08:07:48 volumiox volumio[1080]: info: CoreCommandRouter::servicePushState Apr 28 08:07:48 volumiox volumio[1080]: info: CorePlayQueue::getTrack 0 Apr 28 08:07:48 volumiox volumio[1080]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":359,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"351933722&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/351933722&soundQuality=HI_RES","trackType":"tidal"} Apr 28 08:07:48 volumiox volumio[1080]: verbose: CURRENT POSITION 0 Apr 28 08:07:48 volumiox volumio[1080]: info: CoreStateMachine::syncState stateService play Apr 28 08:07:48 volumiox volumio[1080]: info: CoreStateMachine::syncState currentStatus play Apr 28 08:07:48 volumiox volumio[1080]: info: Received an update from plugin. extracting info from payload Apr 28 08:07:48 volumiox volumio[1080]: info: CoreStateMachine::pushState Apr 28 08:07:48 volumiox volumio[1080]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 08:07:48 volumiox volumio[1080]: info: CoreCommandRouter::volumioPushState Apr 28 08:07:48 volumiox volumio[1080]: info: CoreStateMachine::pushState Apr 28 08:07:48 volumiox volumio[1080]: info: CoreCommandRouter::volumioPushState Apr 28 08:07:48 volumiox volumio[1080]: info: ------------------------------ 66ms Apr 28 08:07:52 volumiox volumio[1080]: STREAMING PROXY: Client dropped request, destroying Apr 28 08:07:57 volumiox volumio[1080]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 28 08:07:57 volumiox volumio[1080]: info: CURURI: music-library Apr 28 08:07:57 volumiox volumio[1080]: info: Preload queue cleared Apr 28 08:07:59 volumiox volumio[1080]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 9 Apr 28 08:07:59 volumiox volumio[1080]: info: CoreCommandRouter::volumioGetState Apr 28 08:08:02 volumiox volumio[1080]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 28 08:08:02 volumiox volumio[1080]: info: Preload queue cleared Apr 28 08:08:03 volumiox volumio[1080]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 28 08:08:03 volumiox volumio[1080]: info: Preload queue cleared Apr 28 08:08:05 volumiox volumio[1080]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 28 08:08:06 volumiox volumio[1080]: info: Preload queue cleared Apr 28 08:08:08 volumiox volumio[1080]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 28 08:08:09 volumiox volumio[1080]: info: Preload queue cleared Apr 28 08:08:15 volumiox volumio[1080]: info: CoreCommandRouter::volumioGetState Apr 28 08:08:17 volumiox volumio[1080]: info: Executing endpoint metavolumio Apr 28 08:08:17 volumiox volumio[1080]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 28 08:08:17 volumiox volumio[1080]: info: Executing endpoint metavolumio Apr 28 08:08:17 volumiox volumio[1080]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 28 08:08:17 volumiox volumio[1080]: info: Executing endpoint metavolumio Apr 28 08:08:17 volumiox volumio[1080]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 28 08:08:17 volumiox volumio[1080]: error: Failed request for metavolumio API Apr 28 08:08:20 volumiox volumio[1080]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 28 08:08:20 volumiox volumio[1080]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 28 08:08:20 volumiox volumio[1080]: info: Retrieving Cloud Streaming UI Apr 28 08:08:20 volumiox volumio[1080]: info: Getting Tidal Cloud Configuration Apr 28 08:08:20 volumiox volumio[1080]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 28 08:08:20 volumiox volumio[1080]: info: Getting Qobuz Cloud Configuration Apr 28 08:08:20 volumiox volumio[1080]: info: Asking plugin for UI Config Apr 28 08:08:20 volumiox volumio[1080]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 28 08:08:20 volumiox volumio[1080]: info: Getting Spotify Cloud Configuration Apr 28 08:08:20 volumiox volumio[1080]: info: Asking plugin for UI Config Apr 28 08:08:20 volumiox volumio[1080]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 28 08:08:20 volumiox volumio[1080]: info: Saving Spotify Acccount Apr 28 08:08:20 volumiox volumio[1080]: info: Got it Apr 28 08:08:20 volumiox volumio[1080]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Apr 28 08:08:20 volumiox volumio[1080]: info: Got Tidal Cloud Configuration Apr 28 08:08:20 volumiox volumio[1080]: info: Got it Apr 28 08:08:20 volumiox volumio[1080]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 28 08:08:20 volumiox volumio[1080]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 28 08:08:20 volumiox volumio[1080]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 28 08:08:20 volumiox volumio[1080]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 28 08:08:20 volumiox volumio[1080]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 28 08:08:20 volumiox volumio[1080]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 28 08:08:20 volumiox volumio[1080]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 28 08:08:20 volumiox volumio[1080]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 28 08:08:20 volumiox volumio[1080]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 28 08:08:20 volumiox volumio[1080]: info: CoreCommandRouter::volumioGetBrowseSources Apr 28 08:08:20 volumiox volumio[1080]: info: CoreCommandRouter::volumioGetBrowseSources Apr 28 08:08:20 volumiox volumio[1080]: info: CoreCommandRouter::volumioGetBrowseSources Apr 28 08:08:20 volumiox volumio[1080]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 28 08:08:20 volumiox volumio[1080]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Apr 28 08:08:24 volumiox volumio[1080]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 28 08:08:28 volumiox volumio[1080]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 28 08:08:32 volumiox volumio[1080]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 28 08:08:32 volumiox volumio[1080]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Apr 28 08:08:32 volumiox volumio[1080]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Apr 28 08:08:32 volumiox volumio[1080]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 28 08:08:32 volumiox volumio[1080]: Error: getaddrinfo -3007 Apr 28 08:08:32 volumiox volumio[1080]: at errnoException (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:199:11) Apr 28 08:08:32 volumiox volumio[1080]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:112:10) Apr 28 08:08:32 volumiox volumio[1080]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) { Apr 28 08:08:32 volumiox volumio[1080]: code: -3007, Apr 28 08:08:32 volumiox volumio[1080]: errno: -3007, Apr 28 08:08:32 volumiox volumio[1080]: syscall: 'getaddrinfo' Apr 28 08:08:32 volumiox volumio[1080]: } Apr 28 08:08:32 volumiox volumio[1080]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 28 08:08:33 volumiox sudo[5779]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-28 08:07 Apr 28 08:08:33 volumiox sudo[5779]: 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="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"