-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Sun 2024-05-05 10:39:24 UTC. -- May 05 10:38:01 ranza-volumio volumio[848]: info: explodeTIDALUri took 129355 milliseconds May 05 10:38:01 ranza-volumio volumio[848]: error: Commandrouter: Cannot explode uri tidal://mymusic/tracks/dateadded-asc/76724189 from service tidal: May 05 10:38:01 ranza-volumio volumio[848]: info: explodeTIDALUri took 129408 milliseconds May 05 10:38:01 ranza-volumio volumio[848]: error: Commandrouter: Cannot explode uri tidal://mymusic/tracks/dateadded-asc/68642203 from service tidal: May 05 10:38:01 ranza-volumio volumio[848]: info: explodeTIDALUri took 132926 milliseconds May 05 10:38:01 ranza-volumio volumio[848]: error: Commandrouter: Cannot explode uri tidal://mymusic/tracks/az/118999318 from service tidal: May 05 10:38:01 ranza-volumio volumio[848]: info: explodeTIDALUri took 132827 milliseconds May 05 10:38:01 ranza-volumio volumio[848]: error: Commandrouter: Cannot explode uri tidal://mymusic/tracks/az/6759180 from service tidal: May 05 10:38:01 ranza-volumio volumio[848]: info: explodeTIDALUri took 133581 milliseconds May 05 10:38:01 ranza-volumio volumio[848]: error: Commandrouter: Cannot explode uri tidal://mymusic/tracks/az/6853716 from service tidal: May 05 10:38:01 ranza-volumio volumio[848]: info: explodeTIDALUri took 133531 milliseconds May 05 10:38:01 ranza-volumio volumio[848]: error: Commandrouter: Cannot explode uri tidal://mymusic/tracks/az/4924933 from service tidal: May 05 10:38:01 ranza-volumio volumio[848]: info: explodeTIDALUri took 133389 milliseconds May 05 10:38:01 ranza-volumio volumio[848]: error: Commandrouter: Cannot explode uri tidal://mymusic/tracks/az/7095624 from service tidal: May 05 10:38:01 ranza-volumio volumio[848]: info: explodeTIDALUri took 133892 milliseconds May 05 10:38:01 ranza-volumio volumio[848]: error: Commandrouter: Cannot explode uri tidal://mymusic/tracks/az/273827986 from service tidal: May 05 10:38:01 ranza-volumio volumio[848]: info: explodeTIDALUri took 133742 milliseconds May 05 10:38:01 ranza-volumio volumio[848]: error: Commandrouter: Cannot explode uri tidal://mymusic/tracks/az/243050187 from service tidal: May 05 10:38:06 ranza-volumio volumio[848]: info: explodeTIDALUri took 132365 milliseconds May 05 10:38:06 ranza-volumio volumio[848]: error: Commandrouter: Cannot explode uri tidal://mymusic/tracks/dateadded-asc/152420736 from service tidal: May 05 10:38:06 ranza-volumio volumio[848]: info: explodeTIDALUri took 129466 milliseconds May 05 10:38:06 ranza-volumio volumio[848]: error: Commandrouter: Cannot explode uri tidal://mymusic/tracks/dateadded/78356480 from service tidal: May 05 10:38:06 ranza-volumio volumio[848]: info: explodeTIDALUri took 133675 milliseconds May 05 10:38:06 ranza-volumio volumio[848]: error: Commandrouter: Cannot explode uri tidal://mymusic/tracks/dateadded-asc/115522443 from service tidal: May 05 10:38:06 ranza-volumio volumio[848]: info: explodeTIDALUri took 129822 milliseconds May 05 10:38:06 ranza-volumio volumio[848]: error: Commandrouter: Cannot explode uri tidal://mymusic/tracks/dateadded/240265810 from service tidal: May 05 10:38:11 ranza-volumio volumio[848]: info: explodeTIDALUri took 133858 milliseconds May 05 10:38:11 ranza-volumio volumio[848]: error: Commandrouter: Cannot explode uri tidal://mymusic/tracks/dateadded/1404361 from service tidal: May 05 10:38:11 ranza-volumio volumio[848]: info: explodeTIDALUri took 133876 milliseconds May 05 10:38:11 ranza-volumio volumio[848]: error: Commandrouter: Cannot explode uri tidal://mymusic/tracks/dateadded/3097247 from service tidal: May 05 10:38:11 ranza-volumio volumio[848]: info: explodeTIDALUri took 133884 milliseconds May 05 10:38:11 ranza-volumio volumio[848]: error: Commandrouter: Cannot explode uri tidal://mymusic/tracks/dateadded/15621645 from service tidal: May 05 10:38:11 ranza-volumio volumio[848]: info: explodeTIDALUri took 133889 milliseconds May 05 10:38:11 ranza-volumio volumio[848]: error: Commandrouter: Cannot explode uri tidal://mymusic/tracks/dateadded/622359 from service tidal: May 05 10:38:11 ranza-volumio volumio[848]: info: explodeTIDALUri took 133964 milliseconds May 05 10:38:11 ranza-volumio volumio[848]: error: Commandrouter: Cannot explode uri tidal://mymusic/tracks/dateadded/154939474 from service tidal: May 05 10:38:11 ranza-volumio volumio[848]: info: explodeTIDALUri took 133969 milliseconds May 05 10:38:11 ranza-volumio volumio[848]: error: Commandrouter: Cannot explode uri tidal://mymusic/tracks/dateadded/43569394 from service tidal: May 05 10:38:12 ranza-volumio volumio[848]: info: explodeTIDALUri took 134008 milliseconds May 05 10:38:12 ranza-volumio volumio[848]: error: Commandrouter: Cannot explode uri tidal://mymusic/tracks/dateadded/135236249 from service tidal: May 05 10:38:12 ranza-volumio volumio[848]: info: CoreCommandRouter::volumioPushQueue May 05 10:38:12 ranza-volumio volumio[848]: info: CorePlayQueue::saveQueue May 05 10:38:12 ranza-volumio volumio[848]: info: CoreStateMachine::updateTrackBlock May 05 10:38:12 ranza-volumio volumio[848]: info: CorePlayQueue::getTrackBlock May 05 10:38:12 ranza-volumio volumio[848]: info: CoreCommandRouter::volumioPlay May 05 10:38:12 ranza-volumio volumio[848]: info: CoreStateMachine::play index 0 May 05 10:38:12 ranza-volumio volumio[848]: info: CoreStateMachine::setConsumeUpdateService undefined May 05 10:38:12 ranza-volumio volumio[848]: info: CoreStateMachine::stop May 05 10:38:12 ranza-volumio volumio[848]: info: CoreStateMachine::setConsumeUpdateService undefined May 05 10:38:12 ranza-volumio volumio[848]: info: CoreStateMachine::stPlaybackTimer May 05 10:38:12 ranza-volumio volumio[848]: info: CoreStateMachine::updateTrackBlock May 05 10:38:12 ranza-volumio volumio[848]: info: CorePlayQueue::getTrackBlock May 05 10:38:12 ranza-volumio volumio[848]: info: CoreStateMachine::pushState May 05 10:38:12 ranza-volumio volumio[848]: info: CorePlayQueue::getTrack 0 May 05 10:38:12 ranza-volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 10:38:12 ranza-volumio volumio[848]: info: CoreCommandRouter::volumioPushState May 05 10:38:12 ranza-volumio volumio[848]: info: MRS: Pushing multiroomSync output update for this device May 05 10:38:12 ranza-volumio volumio[848]: info: MRS: Pushing multiroomSync output May 05 10:38:12 ranza-volumio volumio[848]: info: CoreStateMachine::serviceStop May 05 10:38:12 ranza-volumio volumio[848]: info: CorePlayQueue::getTrack 0 May 05 10:38:12 ranza-volumio volumio[848]: info: CoreCommandRouter::serviceStop May 05 10:38:12 ranza-volumio volumio[848]: info: [1714905492051] ControllerTidal::stop May 05 10:38:12 ranza-volumio volumio[848]: info: CoreStateMachine::setConsumeUpdateService mpd May 05 10:38:12 ranza-volumio volumio[848]: info: ControllerMpd::stop May 05 10:38:12 ranza-volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand stop May 05 10:38:12 ranza-volumio volumio[848]: info: May 05 10:38:12 ranza-volumio volumio[848]: ---------------------------- MPD announces state update: player May 05 10:38:12 ranza-volumio volumio[848]: info: sendMpdCommand stop took 24 milliseconds May 05 10:38:12 ranza-volumio volumio[848]: info: ControllerMpd::getState May 05 10:38:12 ranza-volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand status May 05 10:38:12 ranza-volumio volumio[848]: info: CoreStateMachine::play index undefined May 05 10:38:12 ranza-volumio volumio[848]: info: CoreStateMachine::setConsumeUpdateService undefined May 05 10:38:12 ranza-volumio volumio[848]: info: CorePlayQueue::getTrack 0 May 05 10:38:12 ranza-volumio volumio[848]: info: CoreStateMachine::startPlaybackTimer May 05 10:38:12 ranza-volumio volumio[848]: info: CorePlayQueue::getTrack 0 May 05 10:38:12 ranza-volumio volumio[848]: info: [1714905492081] ControllerTidal::clearAddPlayTrack May 05 10:38:12 ranza-volumio volumio[848]: info: Getting stream with soundQuality LOSSLESS May 05 10:38:12 ranza-volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand stop May 05 10:38:12 ranza-volumio volumio[848]: info: sendMpdCommand status took 7 milliseconds May 05 10:38:12 ranza-volumio volumio[848]: info: sendMpdCommand stop took 4 milliseconds May 05 10:38:12 ranza-volumio volumio[848]: verbose: ControllerMpd::parseState May 05 10:38:12 ranza-volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 05 10:38:12 ranza-volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand clear May 05 10:38:12 ranza-volumio volumio[848]: info: May 05 10:38:12 ranza-volumio volumio[848]: ---------------------------- MPD announces system playlist update May 05 10:38:12 ranza-volumio volumio[848]: info: Ignoring MPD Status Update May 05 10:38:12 ranza-volumio volumio[848]: info: sendMpdCommand playlistinfo took 10 milliseconds May 05 10:38:12 ranza-volumio volumio[848]: info: sendMpdCommand clear took 11 milliseconds May 05 10:38:12 ranza-volumio volumio[848]: verbose: ControllerMpd::parseTrackInfo May 05 10:38:12 ranza-volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/224902859&soundQuality=LOSSLESS" May 05 10:38:12 ranza-volumio volumio[848]: info: ControllerMpd::pushState May 05 10:38:12 ranza-volumio volumio[848]: info: CoreCommandRouter::servicePushState May 05 10:38:12 ranza-volumio volumio[848]: info: CoreStateMachine::pushState May 05 10:38:12 ranza-volumio volumio[848]: info: CorePlayQueue::getTrack 0 May 05 10:38:12 ranza-volumio volumio[848]: info: CoreCommandRouter::volumioPushState May 05 10:38:12 ranza-volumio volumio[848]: info: MRS: Pushing multiroomSync output update for this device May 05 10:38:12 ranza-volumio volumio[848]: info: MRS: Pushing multiroomSync output May 05 10:38:12 ranza-volumio volumio[848]: info: CorePlayQueue::getTrack 0 May 05 10:38:12 ranza-volumio volumio[848]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd May 05 10:38:12 ranza-volumio volumio[848]: info: ------------------------------ 42ms May 05 10:38:12 ranza-volumio volumio[848]: error: updateQueue error: null May 05 10:38:12 ranza-volumio volumio[848]: info: May 05 10:38:12 ranza-volumio volumio[848]: ---------------------------- MPD announces system playlist update May 05 10:38:12 ranza-volumio volumio[848]: info: Ignoring MPD Status Update May 05 10:38:12 ranza-volumio volumio[848]: info: ------------------------------ 29ms May 05 10:38:12 ranza-volumio volumio[848]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/224902859&soundQuality=LOSSLESS" took 25 milliseconds May 05 10:38:12 ranza-volumio volumio[848]: info: CoreStateMachine::setConsumeUpdateService mpd May 05 10:38:12 ranza-volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand play May 05 10:38:12 ranza-volumio volumio[848]: STREAMING PROXY: Handling url /?data=tidal://song/224902859&soundQuality=LOSSLESS May 05 10:38:12 ranza-volumio volumio[848]: info: ------------------------------ 8ms May 05 10:38:12 ranza-volumio volumio[848]: info: sendMpdCommand play took 5 milliseconds May 05 10:38:12 ranza-volumio volumio[848]: info: Executing endpoint getStreamUrltidal May 05 10:38:12 ranza-volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI May 05 10:38:17 ranza-volumio volumio[848]: info: explodeTIDALUri took 132969 milliseconds May 05 10:38:17 ranza-volumio volumio[848]: error: Commandrouter: Cannot explode uri tidal://mymusic/tracks/dateadded/115522443 from service tidal: May 05 10:38:17 ranza-volumio volumio[848]: info: getStreamUrl took 130630 milliseconds May 05 10:38:17 ranza-volumio volumio[848]: error: Could not get TIDAL Stream URL May 05 10:38:17 ranza-volumio volumio[848]: error: Failed to retrieve real stream URL May 05 10:38:17 ranza-volumio volumio[848]: info: explodeTIDALUri took 132987 milliseconds May 05 10:38:17 ranza-volumio volumio[848]: error: Commandrouter: Cannot explode uri tidal://mymusic/tracks/dateadded/622359 from service tidal: May 05 10:38:17 ranza-volumio volumio[848]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl May 05 10:38:17 ranza-volumio volumio[848]: info: explodeTIDALUri took 133003 milliseconds May 05 10:38:17 ranza-volumio volumio[848]: error: Commandrouter: Cannot explode uri tidal://mymusic/tracks/dateadded/40915332 from service tidal: May 05 10:38:17 ranza-volumio volumio[848]: info: explodeTIDALUri took 133046 milliseconds May 05 10:38:17 ranza-volumio volumio[848]: error: Commandrouter: Cannot explode uri tidal://mymusic/tracks/dateadded/40212917 from service tidal: May 05 10:38:17 ranza-volumio volumio[848]: info: CoreCommandRouter::volumioPushQueue May 05 10:38:17 ranza-volumio volumio[848]: info: CorePlayQueue::saveQueue May 05 10:38:17 ranza-volumio volumio[848]: info: CoreStateMachine::updateTrackBlock May 05 10:38:17 ranza-volumio volumio[848]: info: CorePlayQueue::getTrackBlock May 05 10:38:17 ranza-volumio volumio[848]: info: CoreCommandRouter::volumioPlay May 05 10:38:17 ranza-volumio volumio[848]: info: CoreStateMachine::play index 0 May 05 10:38:17 ranza-volumio volumio[848]: info: CoreStateMachine::setConsumeUpdateService undefined May 05 10:38:17 ranza-volumio volumio[848]: info: CoreStateMachine::stop May 05 10:38:17 ranza-volumio volumio[848]: info: CoreStateMachine::setConsumeUpdateService undefined May 05 10:38:17 ranza-volumio volumio[848]: info: CoreStateMachine::play index undefined May 05 10:38:17 ranza-volumio volumio[848]: info: CoreStateMachine::setConsumeUpdateService undefined May 05 10:38:17 ranza-volumio volumio[848]: info: CorePlayQueue::getTrack 0 May 05 10:38:17 ranza-volumio volumio[848]: info: CoreStateMachine::startPlaybackTimer May 05 10:38:17 ranza-volumio volumio[848]: info: CorePlayQueue::getTrack 0 May 05 10:38:17 ranza-volumio volumio[848]: info: [1714905497174] ControllerTidal::clearAddPlayTrack May 05 10:38:17 ranza-volumio volumio[848]: info: Getting stream with soundQuality LOSSLESS May 05 10:38:17 ranza-volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand stop May 05 10:38:17 ranza-volumio volumio[848]: info: May 05 10:38:17 ranza-volumio volumio[848]: ---------------------------- MPD announces state update: player May 05 10:38:17 ranza-volumio volumio[848]: info: sendMpdCommand stop took 4 milliseconds May 05 10:38:17 ranza-volumio volumio[848]: info: ControllerMpd::getState May 05 10:38:17 ranza-volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand status May 05 10:38:17 ranza-volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand clear May 05 10:38:17 ranza-volumio volumio[848]: info: May 05 10:38:17 ranza-volumio volumio[848]: ---------------------------- MPD announces system playlist update May 05 10:38:17 ranza-volumio volumio[848]: info: Ignoring MPD Status Update May 05 10:38:17 ranza-volumio volumio[848]: info: sendMpdCommand status took 3 milliseconds May 05 10:38:17 ranza-volumio volumio[848]: info: sendMpdCommand clear took 2 milliseconds May 05 10:38:17 ranza-volumio volumio[848]: verbose: ControllerMpd::parseState May 05 10:38:17 ranza-volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 05 10:38:17 ranza-volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/224902859&soundQuality=LOSSLESS" May 05 10:38:17 ranza-volumio volumio[848]: error: updateQueue error: null May 05 10:38:17 ranza-volumio volumio[848]: info: May 05 10:38:17 ranza-volumio volumio[848]: ---------------------------- MPD announces system playlist update May 05 10:38:17 ranza-volumio volumio[848]: info: Ignoring MPD Status Update May 05 10:38:17 ranza-volumio volumio[848]: info: ------------------------------ 6ms May 05 10:38:17 ranza-volumio volumio[848]: info: sendMpdCommand playlistinfo took 4 milliseconds May 05 10:38:17 ranza-volumio volumio[848]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/224902859&soundQuality=LOSSLESS" took 4 milliseconds May 05 10:38:17 ranza-volumio volumio[848]: verbose: ControllerMpd::parseTrackInfo May 05 10:38:17 ranza-volumio volumio[848]: info: CoreStateMachine::setConsumeUpdateService mpd May 05 10:38:17 ranza-volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand play May 05 10:38:17 ranza-volumio volumio[848]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 05 10:38:17 ranza-volumio volumio[848]: info: ------------------------------ 11ms May 05 10:38:17 ranza-volumio volumio[848]: info: ------------------------------ 5ms May 05 10:38:17 ranza-volumio volumio[848]: info: sendMpdCommand play took 2 milliseconds May 05 10:38:17 ranza-volumio volumio[848]: STREAMING PROXY: Handling url /?data=tidal://song/224902859&soundQuality=LOSSLESS May 05 10:38:17 ranza-volumio volumio[848]: info: Executing endpoint getStreamUrltidal May 05 10:38:17 ranza-volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI May 05 10:38:17 ranza-volumio volumio[848]: info: getStreamUrl took 378 milliseconds May 05 10:38:17 ranza-volumio volumio[848]: STREAMING PROXY: Got real url: http://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKRInMDY3MDEwNzUzYWZiYWJhZTBkMDIxNjE4MTc1NmU5NjZfNjEubXA0/0.flac?token=1714909032~MjNhYjk3ZDBjNDE1OTljNjFjODMwNGNhMjdiMGQ3MDIyNjM1ZWU1Yg== May 05 10:38:17 ranza-volumio volumio[848]: STREAMING PROXY: Response: 200, length: 33678046 May 05 10:38:17 ranza-volumio volumio[848]: info: May 05 10:38:17 ranza-volumio volumio[848]: ---------------------------- MPD announces state update: player May 05 10:38:17 ranza-volumio volumio[848]: info: ControllerMpd::getState May 05 10:38:17 ranza-volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand status May 05 10:38:17 ranza-volumio volumio[848]: info: sendMpdCommand status took 16 milliseconds May 05 10:38:17 ranza-volumio volumio[848]: verbose: ControllerMpd::parseState May 05 10:38:17 ranza-volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 05 10:38:17 ranza-volumio volumio[848]: info: May 05 10:38:17 ranza-volumio volumio[848]: ---------------------------- MPD announces state update: player May 05 10:38:17 ranza-volumio volumio[848]: info: ControllerMpd::getState May 05 10:38:17 ranza-volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand status May 05 10:38:17 ranza-volumio volumio[848]: info: sendMpdCommand playlistinfo took 4 milliseconds May 05 10:38:17 ranza-volumio volumio[848]: verbose: ControllerMpd::parseTrackInfo May 05 10:38:17 ranza-volumio volumio[848]: info: ControllerMpd::pushState May 05 10:38:17 ranza-volumio volumio[848]: info: CoreCommandRouter::servicePushState May 05 10:38:17 ranza-volumio volumio[848]: info: CorePlayQueue::getTrack 0 May 05 10:38:17 ranza-volumio volumio[848]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":257,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"224902859&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/224902859&soundQuality=LOSSLESS","trackType":"tidal"} May 05 10:38:17 ranza-volumio volumio[848]: verbose: CURRENT POSITION 0 May 05 10:38:17 ranza-volumio volumio[848]: info: CoreStateMachine::syncState stateService play May 05 10:38:17 ranza-volumio volumio[848]: info: CoreStateMachine::syncState currentStatus stop May 05 10:38:17 ranza-volumio volumio[848]: info: ------------------------------ 26ms May 05 10:38:17 ranza-volumio volumio[848]: info: sendMpdCommand status took 6 milliseconds May 05 10:38:17 ranza-volumio volumio[848]: verbose: ControllerMpd::parseState May 05 10:38:17 ranza-volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 05 10:38:17 ranza-volumio volumio[848]: info: sendMpdCommand playlistinfo took 1 milliseconds May 05 10:38:17 ranza-volumio volumio[848]: verbose: ControllerMpd::parseTrackInfo May 05 10:38:17 ranza-volumio volumio[848]: info: ControllerMpd::pushState May 05 10:38:17 ranza-volumio volumio[848]: info: CoreCommandRouter::servicePushState May 05 10:38:17 ranza-volumio volumio[848]: info: CorePlayQueue::getTrack 0 May 05 10:38:17 ranza-volumio volumio[848]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1166,"duration":257,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"537 Kbps","isStreaming":false,"title":"224902859&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/224902859&soundQuality=LOSSLESS","trackType":"tidal"} May 05 10:38:17 ranza-volumio volumio[848]: verbose: CURRENT POSITION 0 May 05 10:38:17 ranza-volumio volumio[848]: info: CoreStateMachine::syncState stateService play May 05 10:38:17 ranza-volumio volumio[848]: info: CoreStateMachine::syncState currentStatus play May 05 10:38:17 ranza-volumio volumio[848]: info: Received an update from plugin. extracting info from payload May 05 10:38:17 ranza-volumio volumio[848]: info: CoreStateMachine::pushState May 05 10:38:17 ranza-volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 10:38:17 ranza-volumio volumio[848]: info: CoreCommandRouter::volumioPushState May 05 10:38:17 ranza-volumio volumio[848]: info: MRS: Pushing multiroomSync output update for this device May 05 10:38:17 ranza-volumio volumio[848]: info: MRS: Pushing multiroomSync output May 05 10:38:17 ranza-volumio volumio[848]: info: CoreStateMachine::pushState May 05 10:38:17 ranza-volumio volumio[848]: info: CoreCommandRouter::volumioPushState May 05 10:38:17 ranza-volumio volumio[848]: info: MRS: Pushing multiroomSync output update for this device May 05 10:38:17 ranza-volumio volumio[848]: info: MRS: Pushing multiroomSync output May 05 10:38:17 ranza-volumio volumio[848]: info: ------------------------------ 42ms May 05 10:38:23 ranza-volumio volumio[848]: STREAMING PROXY: Client dropped request, destroying May 05 10:38:25 ranza-volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 05 10:38:25 ranza-volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 05 10:38:25 ranza-volumio volumio[848]: info: Discovery: Getting this device information May 05 10:38:25 ranza-volumio volumio[848]: info: CoreCommandRouter::volumioGetState May 05 10:38:25 ranza-volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 05 10:38:25 ranza-volumio volumio[848]: verbose: New Socket.io Connection to 192.168.143.191 from 192.168.143.168 UA: unknown Total Clients: 5 May 05 10:38:25 ranza-volumio volumio[848]: info: CoreCommandRouter::volumioGetState May 05 10:38:25 ranza-volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 05 10:38:25 ranza-volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 05 10:38:26 ranza-volumio volumio[848]: verbose: New Socket.io Connection to 192.168.143.191 from 192.168.143.168 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 6 May 05 10:38:28 ranza-volumio sudo[1748]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 05 10:38:28 ranza-volumio sudo[1748]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 10:38:28 ranza-volumio sudo[1748]: pam_unix(sudo:session): session closed for user root May 05 10:38:28 ranza-volumio sudo[1750]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 05 10:38:28 ranza-volumio sudo[1750]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 10:38:28 ranza-volumio sudo[1750]: pam_unix(sudo:session): session closed for user root May 05 10:38:28 ranza-volumio volumio[848]: verbose: New Socket.io Connection to 192.168.143.191 from 192.168.143.168 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 7 May 05 10:38:28 ranza-volumio volumio[848]: info: Executing endpoint metavolumio May 05 10:38:28 ranza-volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 05 10:38:28 ranza-volumio volumio[848]: info: Executing endpoint metavolumio May 05 10:38:28 ranza-volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 05 10:38:28 ranza-volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 05 10:38:28 ranza-volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 05 10:38:28 ranza-volumio volumio[848]: info: CoreCommandRouter::volumioGetVisibleSources May 05 10:38:28 ranza-volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 05 10:38:28 ranza-volumio volumio[848]: info: CoreCommandRouter::volumioGetState May 05 10:38:28 ranza-volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 05 10:38:28 ranza-volumio volumio[848]: info: CoreCommandRouter::volumioGetQueue May 05 10:38:28 ranza-volumio volumio[848]: info: CoreStateMachine::getQueue May 05 10:38:28 ranza-volumio volumio[848]: info: CorePlayQueue::getQueue May 05 10:38:28 ranza-volumio volumio[848]: info: Listing playlists May 05 10:38:28 ranza-volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 05 10:38:28 ranza-volumio volumio[848]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom May 05 10:38:28 ranza-volumio volumio[848]: info: Received Get System Info May 05 10:38:28 ranza-volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 05 10:38:28 ranza-volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 05 10:38:28 ranza-volumio volumio[848]: info: Discovery: Getting this device information May 05 10:38:28 ranza-volumio volumio[848]: info: CoreCommandRouter::volumioGetState May 05 10:38:28 ranza-volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 05 10:38:28 ranza-volumio volumio[848]: info: CoreCommandRouter::volumioGetState May 05 10:38:28 ranza-volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 05 10:38:28 ranza-volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 05 10:38:28 ranza-volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 05 10:38:30 ranza-volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 05 10:38:30 ranza-volumio volumio[848]: info: Received Get System Info May 05 10:38:30 ranza-volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 05 10:38:30 ranza-volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 05 10:38:30 ranza-volumio volumio[848]: info: Discovery: Getting this device information May 05 10:38:30 ranza-volumio volumio[848]: info: CoreCommandRouter::volumioGetState May 05 10:38:30 ranza-volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 05 10:38:31 ranza-volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 05 10:38:31 ranza-volumio volumio[848]: info: Received Get System Info May 05 10:38:31 ranza-volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 05 10:38:31 ranza-volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 05 10:38:31 ranza-volumio volumio[848]: info: Discovery: Getting this device information May 05 10:38:31 ranza-volumio volumio[848]: info: CoreCommandRouter::volumioGetState May 05 10:38:31 ranza-volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 05 10:38:32 ranza-volumio volumio[848]: info: CoreCommandRouter::volumioNext May 05 10:38:32 ranza-volumio volumio[848]: info: CoreStateMachine::next May 05 10:38:32 ranza-volumio volumio[848]: info: [1714905512068] ControllerTidal::next May 05 10:38:32 ranza-volumio volumio[848]: info: CoreStateMachine::setConsumeUpdateService undefined May 05 10:38:32 ranza-volumio volumio[848]: info: CoreStateMachine::next May 05 10:38:32 ranza-volumio volumio[848]: info: CoreStateMachine::stop May 05 10:38:32 ranza-volumio volumio[848]: info: CoreStateMachine::setConsumeUpdateService undefined May 05 10:38:32 ranza-volumio volumio[848]: info: CoreStateMachine::stPlaybackTimer May 05 10:38:32 ranza-volumio volumio[848]: info: CoreStateMachine::updateTrackBlock May 05 10:38:32 ranza-volumio volumio[848]: info: CorePlayQueue::getTrackBlock May 05 10:38:32 ranza-volumio volumio[848]: info: CoreStateMachine::pushState May 05 10:38:32 ranza-volumio volumio[848]: info: CorePlayQueue::getTrack 0 May 05 10:38:32 ranza-volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 10:38:32 ranza-volumio volumio[848]: info: CoreCommandRouter::volumioPushState May 05 10:38:32 ranza-volumio volumio[848]: info: MRS: Pushing multiroomSync output update for this device May 05 10:38:32 ranza-volumio volumio[848]: info: MRS: Pushing multiroomSync output May 05 10:38:32 ranza-volumio volumio[848]: info: CoreStateMachine::serviceStop May 05 10:38:32 ranza-volumio volumio[848]: info: CorePlayQueue::getTrack 0 May 05 10:38:32 ranza-volumio volumio[848]: info: CoreCommandRouter::serviceStop May 05 10:38:32 ranza-volumio volumio[848]: info: [1714905512086] ControllerTidal::stop May 05 10:38:32 ranza-volumio volumio[848]: info: CoreStateMachine::setConsumeUpdateService mpd May 05 10:38:32 ranza-volumio volumio[848]: info: ControllerMpd::stop May 05 10:38:32 ranza-volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand stop May 05 10:38:32 ranza-volumio volumio[848]: info: May 05 10:38:32 ranza-volumio volumio[848]: ---------------------------- MPD announces state update: player May 05 10:38:32 ranza-volumio volumio[848]: info: sendMpdCommand stop took 25 milliseconds May 05 10:38:32 ranza-volumio volumio[848]: info: ControllerMpd::getState May 05 10:38:32 ranza-volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand status May 05 10:38:32 ranza-volumio volumio[848]: info: CoreStateMachine::play index undefined May 05 10:38:32 ranza-volumio volumio[848]: info: CoreStateMachine::setConsumeUpdateService undefined May 05 10:38:32 ranza-volumio volumio[848]: info: CorePlayQueue::getTrack 1 May 05 10:38:32 ranza-volumio volumio[848]: info: CoreStateMachine::startPlaybackTimer May 05 10:38:32 ranza-volumio volumio[848]: info: CorePlayQueue::getTrack 1 May 05 10:38:32 ranza-volumio volumio[848]: info: [1714905512114] ControllerTidal::clearAddPlayTrack May 05 10:38:32 ranza-volumio volumio[848]: info: Getting stream with soundQuality LOSSLESS May 05 10:38:32 ranza-volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand stop May 05 10:38:32 ranza-volumio volumio[848]: info: CoreStateMachine::updateTrackBlock May 05 10:38:32 ranza-volumio volumio[848]: info: CorePlayQueue::getTrackBlock May 05 10:38:32 ranza-volumio volumio[848]: info: sendMpdCommand status took 8 milliseconds May 05 10:38:32 ranza-volumio volumio[848]: info: sendMpdCommand stop took 6 milliseconds May 05 10:38:32 ranza-volumio volumio[848]: verbose: ControllerMpd::parseState May 05 10:38:32 ranza-volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 05 10:38:32 ranza-volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand clear May 05 10:38:32 ranza-volumio volumio[848]: info: May 05 10:38:32 ranza-volumio volumio[848]: ---------------------------- MPD announces system playlist update May 05 10:38:32 ranza-volumio volumio[848]: info: Ignoring MPD Status Update May 05 10:38:32 ranza-volumio volumio[848]: info: sendMpdCommand playlistinfo took 2 milliseconds May 05 10:38:32 ranza-volumio volumio[848]: info: sendMpdCommand clear took 2 milliseconds May 05 10:38:32 ranza-volumio volumio[848]: verbose: ControllerMpd::parseTrackInfo May 05 10:38:32 ranza-volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/224902860&soundQuality=LOSSLESS" May 05 10:38:32 ranza-volumio volumio[848]: info: ControllerMpd::pushState May 05 10:38:32 ranza-volumio volumio[848]: info: CoreCommandRouter::servicePushState May 05 10:38:32 ranza-volumio volumio[848]: info: CoreStateMachine::pushState May 05 10:38:32 ranza-volumio volumio[848]: info: CorePlayQueue::getTrack 1 May 05 10:38:32 ranza-volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 10:38:32 ranza-volumio volumio[848]: info: CoreCommandRouter::volumioPushState May 05 10:38:32 ranza-volumio volumio[848]: info: MRS: Pushing multiroomSync output update for this device May 05 10:38:32 ranza-volumio volumio[848]: info: MRS: Pushing multiroomSync output May 05 10:38:32 ranza-volumio volumio[848]: info: CorePlayQueue::getTrack 1 May 05 10:38:32 ranza-volumio volumio[848]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd May 05 10:38:32 ranza-volumio volumio[848]: info: ------------------------------ 39ms May 05 10:38:32 ranza-volumio volumio[848]: error: updateQueue error: null May 05 10:38:32 ranza-volumio volumio[848]: info: May 05 10:38:32 ranza-volumio volumio[848]: ---------------------------- MPD announces system playlist update May 05 10:38:32 ranza-volumio volumio[848]: info: Ignoring MPD Status Update May 05 10:38:32 ranza-volumio volumio[848]: info: ------------------------------ 36ms May 05 10:38:32 ranza-volumio volumio[848]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/224902860&soundQuality=LOSSLESS" took 34 milliseconds May 05 10:38:32 ranza-volumio volumio[848]: info: CoreStateMachine::setConsumeUpdateService mpd May 05 10:38:32 ranza-volumio volumio[848]: verbose: ControllerMpd::sendMpdCommand play May 05 10:38:32 ranza-volumio volumio[848]: STREAMING PROXY: Handling url /?data=tidal://song/224902860&soundQuality=LOSSLESS May 05 10:38:32 ranza-volumio volumio[848]: info: ------------------------------ 24ms May 05 10:38:32 ranza-volumio volumio[848]: info: sendMpdCommand play took 23 milliseconds May 05 10:38:32 ranza-volumio volumio[848]: info: Executing endpoint getStreamUrltidal May 05 10:38:32 ranza-volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI May 05 10:38:32 ranza-volumio volumio[848]: info: getStreamUrl took 359 milliseconds May 05 10:38:32 ranza-volumio volumio[848]: STREAMING PROXY: Got real url: http://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKRInNzNiMmMzZTk0YTE5YjVlZGQ3ZjFjNGVjZWNjNDIwYTBfNjEubXA0/0.flac?token=1714909112~M2I1M2VjNWEwOGZmMjU1YzYzMWEyMTEwYjg0Mjk2ZDgyYmI2NTVhZQ== May 05 10:38:35 ranza-volumio volumio[848]: error: Failed request for metavolumio API May 05 10:38:41 ranza-volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 05 10:38:41 ranza-volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 05 10:38:41 ranza-volumio volumio[848]: info: Discovery: Getting this device information May 05 10:38:41 ranza-volumio volumio[848]: info: CoreCommandRouter::volumioGetState May 05 10:38:41 ranza-volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 05 10:38:41 ranza-volumio volumio[848]: verbose: New Socket.io Connection to 192.168.143.191 from 192.168.143.168 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 7 May 05 10:38:41 ranza-volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 05 10:38:41 ranza-volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 05 10:38:41 ranza-volumio volumio[848]: info: CoreCommandRouter::volumioGetVisibleSources May 05 10:38:41 ranza-volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 05 10:38:41 ranza-volumio volumio[848]: info: CoreCommandRouter::volumioGetState May 05 10:38:41 ranza-volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 05 10:38:41 ranza-volumio volumio[848]: info: CoreCommandRouter::volumioGetQueue May 05 10:38:41 ranza-volumio volumio[848]: info: CoreStateMachine::getQueue May 05 10:38:41 ranza-volumio volumio[848]: info: CorePlayQueue::getQueue May 05 10:38:41 ranza-volumio volumio[848]: info: Listing playlists May 05 10:38:41 ranza-volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 05 10:38:41 ranza-volumio volumio[848]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom May 05 10:38:41 ranza-volumio volumio[848]: info: Received Get System Info May 05 10:38:41 ranza-volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 05 10:38:41 ranza-volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 05 10:38:41 ranza-volumio volumio[848]: info: Discovery: Getting this device information May 05 10:38:41 ranza-volumio volumio[848]: info: CoreCommandRouter::volumioGetState May 05 10:38:41 ranza-volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 05 10:38:41 ranza-volumio volumio[848]: info: CoreCommandRouter::volumioGetState May 05 10:38:41 ranza-volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 05 10:38:43 ranza-volumio volumiologrotate[504]: ls: cannot access '/var/log/samba/log.wb-RANZA': No such file or directory May 05 10:38:43 ranza-volumio volumiologrotate[504]: ls: cannot access 'VOLUMIO': No such file or directory May 05 10:38:45 ranza-volumio volumio[848]: info: Executing endpoint metavolumio May 05 10:38:45 ranza-volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 05 10:38:45 ranza-volumio volumio[848]: info: Executing endpoint metavolumio May 05 10:38:45 ranza-volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 05 10:38:49 ranza-volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 05 10:38:49 ranza-volumio volumio[848]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 05 10:38:49 ranza-volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 05 10:38:49 ranza-volumio volumio[848]: info: Received Get System Version May 05 10:38:49 ranza-volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 05 10:38:49 ranza-volumio volumio[848]: info: Received Get System Info May 05 10:38:49 ranza-volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 05 10:38:49 ranza-volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 05 10:38:49 ranza-volumio volumio[848]: info: Discovery: Getting this device information May 05 10:38:49 ranza-volumio volumio[848]: info: CoreCommandRouter::volumioGetState May 05 10:38:49 ranza-volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 05 10:38:49 ranza-volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 05 10:38:49 ranza-volumio volumio[848]: info: CURURI: music-library May 05 10:38:49 ranza-volumio volumio[848]: error: Failed LSINFO: null May 05 10:38:49 ranza-volumio volumio[848]: info: Preload queue cleared May 05 10:38:52 ranza-volumio volumio[848]: error: Failed request for metavolumio API May 05 10:38:53 ranza-volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri May 05 10:38:53 ranza-volumio volumio[848]: info: browseTIDALUri took 166 milliseconds May 05 10:38:54 ranza-volumio volumio[848]: info: Preload queue cleared May 05 10:38:54 ranza-volumio volumio[848]: info: Preload queue cleared May 05 10:38:55 ranza-volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri May 05 10:39:04 ranza-volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri May 05 10:39:05 ranza-volumio volumio[848]: info: browseTIDALUri took 163 milliseconds May 05 10:39:05 ranza-volumio volumio[848]: info: Preload queue cleared May 05 10:39:05 ranza-volumio volumio[848]: info: Preload queue cleared May 05 10:39:07 ranza-volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri May 05 10:39:07 ranza-volumio volumio[848]: info: browseTIDALUri took 318 milliseconds May 05 10:39:07 ranza-volumio volumio[848]: info: Preload queue cleared May 05 10:39:07 ranza-volumio volumio[848]: info: Preload queue cleared May 05 10:39:09 ranza-volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri May 05 10:39:10 ranza-volumio volumio[848]: info: browseTIDALUri took 773 milliseconds May 05 10:39:10 ranza-volumio volumio[848]: info: Preload queue cleared May 05 10:39:10 ranza-volumio volumio[848]: info: Preload queue cleared May 05 10:39:10 ranza-volumio volumio[848]: info: Preloading song: tidal://mymusic/tracks/az/350722 May 05 10:39:10 ranza-volumio volumio[848]: info: Preloading song: tidal://mymusic/tracks/az/86096855 May 05 10:39:10 ranza-volumio volumio[848]: info: Preloading song: tidal://mymusic/tracks/az/135236249 May 05 10:39:10 ranza-volumio volumio[848]: info: Preloading song: tidal://mymusic/tracks/az/19291980 May 05 10:39:10 ranza-volumio volumio[848]: info: Preloading song: tidal://mymusic/tracks/az/275837559 May 05 10:39:10 ranza-volumio volumio[848]: info: Preloading song: tidal://mymusic/tracks/az/184718363 May 05 10:39:10 ranza-volumio volumio[848]: info: Preloading song: tidal://mymusic/tracks/az/232946928 May 05 10:39:10 ranza-volumio volumio[848]: info: Preloading song: tidal://mymusic/tracks/az/80896987 May 05 10:39:10 ranza-volumio volumio[848]: info: Preloading song: tidal://mymusic/tracks/az/275614027 May 05 10:39:10 ranza-volumio volumio[848]: info: Preloading song: tidal://mymusic/tracks/az/154939474 May 05 10:39:10 ranza-volumio volumio[848]: info: Preloading song: tidal://mymusic/tracks/az/216746100 May 05 10:39:10 ranza-volumio volumio[848]: info: Preloading song: tidal://mymusic/tracks/az/47896562 May 05 10:39:10 ranza-volumio volumio[848]: info: Preloading song: tidal://mymusic/tracks/az/68642203 May 05 10:39:10 ranza-volumio volumio[848]: info: Preloading song: tidal://mymusic/tracks/az/22663572 May 05 10:39:10 ranza-volumio volumio[848]: info: Preloading song: tidal://mymusic/tracks/az/50096782 May 05 10:39:10 ranza-volumio volumio[848]: info: Preloading song: tidal://mymusic/tracks/az/105418592 May 05 10:39:10 ranza-volumio volumio[848]: info: Preloading song: tidal://mymusic/tracks/az/101030721 May 05 10:39:10 ranza-volumio volumio[848]: info: Preloading song: tidal://mymusic/tracks/az/241759243 May 05 10:39:10 ranza-volumio volumio[848]: info: Preloading song: tidal://mymusic/tracks/az/43569394 May 05 10:39:10 ranza-volumio volumio[848]: info: Preloading song: tidal://mymusic/tracks/az/40212917 May 05 10:39:10 ranza-volumio volumio[848]: info: Preloading song: tidal://mymusic/tracks/az/23011748 May 05 10:39:10 ranza-volumio volumio[848]: info: Preloading song: tidal://mymusic/tracks/az/273827986 May 05 10:39:10 ranza-volumio volumio[848]: info: Preloading song: tidal://mymusic/tracks/az/67907787 May 05 10:39:10 ranza-volumio volumio[848]: info: Preloading song: tidal://mymusic/tracks/az/243050187 May 05 10:39:10 ranza-volumio volumio[848]: info: Preloading song: tidal://mymusic/tracks/az/6853716 May 05 10:39:10 ranza-volumio volumio[848]: info: Preloading song: tidal://mymusic/tracks/az/4924933 May 05 10:39:10 ranza-volumio volumio[848]: info: Preloading song: tidal://mymusic/tracks/az/7095624 May 05 10:39:10 ranza-volumio volumio[848]: info: Preloading song: tidal://mymusic/tracks/az/115522443 May 05 10:39:10 ranza-volumio volumio[848]: info: Preloading song: tidal://mymusic/tracks/az/118999318 May 05 10:39:10 ranza-volumio volumio[848]: info: Preloading song: tidal://mymusic/tracks/az/40915361 May 05 10:39:10 ranza-volumio volumio[848]: info: Preloading song: tidal://mymusic/tracks/az/6759180 May 05 10:39:10 ranza-volumio volumio[848]: info: Exploding uri tidal://mymusic/tracks/az/350722 in service tidal May 05 10:39:10 ranza-volumio volumio[848]: info: Exploding uri tidal://mymusic/tracks/az/86096855 in service tidal May 05 10:39:10 ranza-volumio volumio[848]: info: Exploding uri tidal://mymusic/tracks/az/135236249 in service tidal May 05 10:39:10 ranza-volumio volumio[848]: info: Exploding uri tidal://mymusic/tracks/az/19291980 in service tidal May 05 10:39:10 ranza-volumio volumio[848]: info: explodeTIDALUri took 177 milliseconds May 05 10:39:10 ranza-volumio volumio[848]: info: Exploding uri tidal://mymusic/tracks/az/275837559 in service tidal May 05 10:39:10 ranza-volumio volumio[848]: info: Exploding uri tidal://mymusic/tracks/az/184718363 in service tidal May 05 10:39:10 ranza-volumio volumio[848]: info: Exploding uri tidal://mymusic/tracks/az/232946928 in service tidal May 05 10:39:10 ranza-volumio volumio[848]: info: Exploding uri tidal://mymusic/tracks/az/80896987 in service tidal May 05 10:39:10 ranza-volumio volumio[848]: info: explodeTIDALUri took 310 milliseconds May 05 10:39:10 ranza-volumio volumio[848]: error: Commandrouter: Cannot explode uri tidal://mymusic/tracks/az/86096855 from service tidal: May 05 10:39:10 ranza-volumio volumio[848]: info: explodeTIDALUri took 304 milliseconds May 05 10:39:10 ranza-volumio volumio[848]: error: Commandrouter: Cannot explode uri tidal://mymusic/tracks/az/135236249 from service tidal: May 05 10:39:10 ranza-volumio volumio[848]: info: Exploding uri tidal://mymusic/tracks/az/275614027 in service tidal May 05 10:39:10 ranza-volumio volumio[848]: info: explodeTIDALUri took 260 milliseconds May 05 10:39:10 ranza-volumio volumio[848]: info: explodeTIDALUri took 252 milliseconds May 05 10:39:10 ranza-volumio volumio[848]: info: Exploding uri tidal://mymusic/tracks/az/154939474 in service tidal May 05 10:39:10 ranza-volumio volumio[848]: info: explodeTIDALUri took 206 milliseconds May 05 10:39:10 ranza-volumio volumio[848]: info: Exploding uri tidal://mymusic/tracks/az/216746100 in service tidal May 05 10:39:10 ranza-volumio volumio[848]: info: explodeTIDALUri took 209 milliseconds May 05 10:39:10 ranza-volumio volumio[848]: info: Exploding uri tidal://mymusic/tracks/az/47896562 in service tidal May 05 10:39:10 ranza-volumio volumio[848]: info: explodeTIDALUri took 211 milliseconds May 05 10:39:10 ranza-volumio volumio[848]: info: Exploding uri tidal://mymusic/tracks/az/68642203 in service tidal May 05 10:39:10 ranza-volumio volumio[848]: info: explodeTIDALUri took 209 milliseconds May 05 10:39:10 ranza-volumio volumio[848]: info: Exploding uri tidal://mymusic/tracks/az/22663572 in service tidal May 05 10:39:10 ranza-volumio volumio[848]: info: Exploding uri tidal://mymusic/tracks/az/50096782 in service tidal May 05 10:39:10 ranza-volumio volumio[848]: info: Exploding uri tidal://mymusic/tracks/az/105418592 in service tidal May 05 10:39:11 ranza-volumio volumio[848]: info: explodeTIDALUri took 306 milliseconds May 05 10:39:11 ranza-volumio volumio[848]: error: Commandrouter: Cannot explode uri tidal://mymusic/tracks/az/216746100 from service tidal: May 05 10:39:11 ranza-volumio volumio[848]: info: Exploding uri tidal://mymusic/tracks/az/101030721 in service tidal May 05 10:39:11 ranza-volumio volumio[848]: info: explodeTIDALUri took 212 milliseconds May 05 10:39:11 ranza-volumio volumio[848]: info: Exploding uri tidal://mymusic/tracks/az/241759243 in service tidal May 05 10:39:11 ranza-volumio volumio[848]: info: explodeTIDALUri took 216 milliseconds May 05 10:39:11 ranza-volumio volumio[848]: info: Exploding uri tidal://mymusic/tracks/az/43569394 in service tidal May 05 10:39:11 ranza-volumio volumio[848]: info: explodeTIDALUri took 200 milliseconds May 05 10:39:11 ranza-volumio volumio[848]: info: Exploding uri tidal://mymusic/tracks/az/40212917 in service tidal May 05 10:39:11 ranza-volumio volumio[848]: info: explodeTIDALUri took 192 milliseconds May 05 10:39:11 ranza-volumio volumio[848]: info: Exploding uri tidal://mymusic/tracks/az/23011748 in service tidal May 05 10:39:11 ranza-volumio volumio[848]: info: Exploding uri tidal://mymusic/tracks/az/273827986 in service tidal May 05 10:39:11 ranza-volumio volumio[848]: info: explodeTIDALUri took 533 milliseconds May 05 10:39:11 ranza-volumio volumio[848]: error: Commandrouter: Cannot explode uri tidal://mymusic/tracks/az/47896562 from service tidal: May 05 10:39:11 ranza-volumio volumio[848]: info: explodeTIDALUri took 193 milliseconds May 05 10:39:11 ranza-volumio volumio[848]: info: Exploding uri tidal://mymusic/tracks/az/67907787 in service tidal May 05 10:39:11 ranza-volumio volumio[848]: info: Exploding uri tidal://mymusic/tracks/az/243050187 in service tidal May 05 10:39:11 ranza-volumio volumio[848]: info: explodeTIDALUri took 318 milliseconds May 05 10:39:11 ranza-volumio volumio[848]: error: Commandrouter: Cannot explode uri tidal://mymusic/tracks/az/241759243 from service tidal: May 05 10:39:11 ranza-volumio volumio[848]: info: Exploding uri tidal://mymusic/tracks/az/6853716 in service tidal May 05 10:39:11 ranza-volumio volumio[848]: info: explodeTIDALUri took 302 milliseconds May 05 10:39:11 ranza-volumio volumio[848]: error: Commandrouter: Cannot explode uri tidal://mymusic/tracks/az/40212917 from service tidal: May 05 10:39:11 ranza-volumio volumio[848]: info: Exploding uri tidal://mymusic/tracks/az/4924933 in service tidal May 05 10:39:11 ranza-volumio volumio[848]: info: explodeTIDALUri took 207 milliseconds May 05 10:39:11 ranza-volumio volumio[848]: info: Exploding uri tidal://mymusic/tracks/az/7095624 in service tidal May 05 10:39:11 ranza-volumio volumio[848]: info: explodeTIDALUri took 333 milliseconds May 05 10:39:11 ranza-volumio volumio[848]: error: Commandrouter: Cannot explode uri tidal://mymusic/tracks/az/23011748 from service tidal: May 05 10:39:11 ranza-volumio volumio[848]: info: Exploding uri tidal://mymusic/tracks/az/115522443 in service tidal May 05 10:39:11 ranza-volumio volumio[848]: info: explodeTIDALUri took 220 milliseconds May 05 10:39:11 ranza-volumio volumio[848]: info: Exploding uri tidal://mymusic/tracks/az/118999318 in service tidal May 05 10:39:11 ranza-volumio volumio[848]: info: explodeTIDALUri took 227 milliseconds May 05 10:39:11 ranza-volumio volumio[848]: info: Exploding uri tidal://mymusic/tracks/az/40915361 in service tidal May 05 10:39:11 ranza-volumio volumio[848]: info: explodeTIDALUri took 392 milliseconds May 05 10:39:11 ranza-volumio volumio[848]: error: Commandrouter: Cannot explode uri tidal://mymusic/tracks/az/67907787 from service tidal: May 05 10:39:11 ranza-volumio volumio[848]: info: Exploding uri tidal://mymusic/tracks/az/6759180 in service tidal May 05 10:39:11 ranza-volumio volumio[848]: info: explodeTIDALUri took 209 milliseconds May 05 10:39:11 ranza-volumio volumio[848]: info: explodeTIDALUri took 272 milliseconds May 05 10:39:11 ranza-volumio volumio[848]: info: explodeTIDALUri took 159 milliseconds May 05 10:39:11 ranza-volumio volumio[848]: info: explodeTIDALUri took 302 milliseconds May 05 10:39:11 ranza-volumio volumio[848]: error: Commandrouter: Cannot explode uri tidal://mymusic/tracks/az/115522443 from service tidal: May 05 10:39:12 ranza-volumio volumio[848]: info: explodeTIDALUri took 314 milliseconds May 05 10:39:12 ranza-volumio volumio[848]: error: Commandrouter: Cannot explode uri tidal://mymusic/tracks/az/6759180 from service tidal: May 05 10:39:12 ranza-volumio volumio[848]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri May 05 10:39:17 ranza-volumio volumio[848]: info: CoreCommandRouter::volumioPause May 05 10:39:17 ranza-volumio volumio[848]: info: CoreStateMachine::pause May 05 10:39:17 ranza-volumio volumio[848]: info: CoreCommandRouter::volumioPause May 05 10:39:17 ranza-volumio volumio[848]: info: CoreStateMachine::pause May 05 10:39:23 ranza-volumio volumio[848]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 05 10:39:23 ranza-volumio volumio[848]: Error: connect ETIMEDOUT 151.101.242.79:443 May 05 10:39:23 ranza-volumio volumio[848]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { May 05 10:39:23 ranza-volumio volumio[848]: errno: -110, May 05 10:39:23 ranza-volumio volumio[848]: code: 'ETIMEDOUT', May 05 10:39:23 ranza-volumio volumio[848]: syscall: 'connect', May 05 10:39:23 ranza-volumio volumio[848]: address: '151.101.242.79', May 05 10:39:23 ranza-volumio volumio[848]: port: 443 May 05 10:39:23 ranza-volumio volumio[848]: } May 05 10:39:23 ranza-volumio volumio[848]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 05 10:39:24 ranza-volumio sudo[1807]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-05 10:38 May 05 10:39:24 ranza-volumio sudo[1807]: 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="e7445fab547cd22e23d9bef72406cfb610bc1d7d" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="a9e1e1dd6072ffb15c0fcb8938d9ea0d8900c0da" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 24 Apr 2024 02:10:14 PM CEST" VOLUMIO_VERSION="3.661" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5bdefd4af4423fd17aacb83a8538bf1f"