-- Logs begin at Fri 2025-11-14 20:57:33 CET, end at Mon 2025-12-29 15:56:19 CET. --
Dec 29 15:55:27 rivoplus volumio[3266]: info: Preload queue cleared
Dec 29 15:55:27 rivoplus volumio[3266]: info: CoreCommandRouter::volumioReplaceandPlayItems
Dec 29 15:55:27 rivoplus volumio[3266]: info: CoreStateMachine::ClearQueue
Dec 29 15:55:27 rivoplus volumio[3266]: info: CoreStateMachine::stop
Dec 29 15:55:27 rivoplus volumio[3266]: info: CoreStateMachine::setConsumeUpdateService undefined
Dec 29 15:55:27 rivoplus volumio[3266]: info: CoreStateMachine::stPlaybackTimer
Dec 29 15:55:27 rivoplus volumio[3266]: info: CoreStateMachine::updateTrackBlock
Dec 29 15:55:27 rivoplus volumio[3266]: info: CorePlayQueue::getTrackBlock
Dec 29 15:55:27 rivoplus volumio[3266]: info: CoreStateMachine::pushState
Dec 29 15:55:27 rivoplus volumio[3266]: info: CorePlayQueue::getTrack 45
Dec 29 15:55:27 rivoplus volumio[3266]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 29 15:55:27 rivoplus volumio[3266]: info: CoreCommandRouter::volumioPushState
Dec 29 15:55:27 rivoplus volumio[3266]: info: CoreStateMachine::serviceStop
Dec 29 15:55:27 rivoplus volumio[3266]: info: CorePlayQueue::getTrack 45
Dec 29 15:55:27 rivoplus volumio[3266]: info: CoreCommandRouter::serviceStop
Dec 29 15:55:27 rivoplus volumio[3266]: info: [1767020127201] ControllerTidal::stop
Dec 29 15:55:27 rivoplus volumio[3266]: info: CoreStateMachine::setConsumeUpdateService mpd
Dec 29 15:55:27 rivoplus volumio[3266]: info: ControllerMpd::stop
Dec 29 15:55:27 rivoplus volumio[3266]: verbose: ControllerMpd::sendMpdCommand stop
Dec 29 15:55:27 rivoplus volumio[3266]: info: CorePlayQueue::clearPlayQueue
Dec 29 15:55:27 rivoplus volumio[3266]: info: CorePlayQueue::saveQueue
Dec 29 15:55:27 rivoplus volumio[3266]: info: CoreCommandRouter::volumioPushQueue
Dec 29 15:55:27 rivoplus volumio[3266]: info: CoreStateMachine::addQueueItems
Dec 29 15:55:27 rivoplus volumio[3266]: info: CorePlayQueue::addQueueItems
Dec 29 15:55:27 rivoplus volumio[3266]: info: Preload queue cleared
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/16024087
Dec 29 15:55:27 rivoplus volumio[3266]: info: Using cached record of: tidal://song/16024087
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/99920827
Dec 29 15:55:27 rivoplus volumio[3266]: info: Using cached record of: tidal://song/99920827
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/68988294
Dec 29 15:55:27 rivoplus volumio[3266]: info: Using cached record of: tidal://song/68988294
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/4167367
Dec 29 15:55:27 rivoplus volumio[3266]: info: Using cached record of: tidal://song/4167367
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/396060895
Dec 29 15:55:27 rivoplus volumio[3266]: info: Using cached record of: tidal://song/396060895
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/396060905
Dec 29 15:55:27 rivoplus volumio[3266]: info: Using cached record of: tidal://song/396060905
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/396060897
Dec 29 15:55:27 rivoplus volumio[3266]: info: Using cached record of: tidal://song/396060897
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/396060900
Dec 29 15:55:27 rivoplus volumio[3266]: info: Using cached record of: tidal://song/396060900
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/266261642
Dec 29 15:55:27 rivoplus volumio[3266]: info: Using cached record of: tidal://song/266261642
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/96899507
Dec 29 15:55:27 rivoplus volumio[3266]: info: Using cached record of: tidal://song/96899507
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/123150061
Dec 29 15:55:27 rivoplus volumio[3266]: info: Using cached record of: tidal://song/123150061
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/182951917
Dec 29 15:55:27 rivoplus volumio[3266]: info: Exploding uri tidal://song/182951917 in service tidal
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/182951923
Dec 29 15:55:27 rivoplus volumio[3266]: info: Exploding uri tidal://song/182951923 in service tidal
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/175542203
Dec 29 15:55:27 rivoplus volumio[3266]: info: Using cached record of: tidal://song/175542203
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/23567300
Dec 29 15:55:27 rivoplus volumio[3266]: info: Using cached record of: tidal://song/23567300
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/51017424
Dec 29 15:55:27 rivoplus volumio[3266]: info: Using cached record of: tidal://song/51017424
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/51017425
Dec 29 15:55:27 rivoplus volumio[3266]: info: Using cached record of: tidal://song/51017425
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/111491556
Dec 29 15:55:27 rivoplus volumio[3266]: info: Exploding uri tidal://song/111491556 in service tidal
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/88393483
Dec 29 15:55:27 rivoplus volumio[3266]: info: Using cached record of: tidal://song/88393483
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/71882758
Dec 29 15:55:27 rivoplus volumio[3266]: info: Using cached record of: tidal://song/71882758
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/142841005
Dec 29 15:55:27 rivoplus volumio[3266]: info: Using cached record of: tidal://song/142841005
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/172902026
Dec 29 15:55:27 rivoplus volumio[3266]: info: Using cached record of: tidal://song/172902026
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/174184210
Dec 29 15:55:27 rivoplus volumio[3266]: info: Using cached record of: tidal://song/174184210
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/6803518
Dec 29 15:55:27 rivoplus volumio[3266]: info: Using cached record of: tidal://song/6803518
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/6803520
Dec 29 15:55:27 rivoplus volumio[3266]: info: Using cached record of: tidal://song/6803520
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/16024089
Dec 29 15:55:27 rivoplus volumio[3266]: info: Using cached record of: tidal://song/16024089
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/206092212
Dec 29 15:55:27 rivoplus volumio[3266]: info: Using cached record of: tidal://song/206092212
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/206092214
Dec 29 15:55:27 rivoplus volumio[3266]: info: Using cached record of: tidal://song/206092214
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/18057959
Dec 29 15:55:27 rivoplus volumio[3266]: info: Using cached record of: tidal://song/18057959
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/17715072
Dec 29 15:55:27 rivoplus volumio[3266]: info: Using cached record of: tidal://song/17715072
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/87252049
Dec 29 15:55:27 rivoplus volumio[3266]: info: Using cached record of: tidal://song/87252049
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/202266421
Dec 29 15:55:27 rivoplus volumio[3266]: info: Using cached record of: tidal://song/202266421
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/257767340
Dec 29 15:55:27 rivoplus volumio[3266]: info: Using cached record of: tidal://song/257767340
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/257767341
Dec 29 15:55:27 rivoplus volumio[3266]: info: Using cached record of: tidal://song/257767341
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/257767342
Dec 29 15:55:27 rivoplus volumio[3266]: info: Using cached record of: tidal://song/257767342
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/11344524
Dec 29 15:55:27 rivoplus volumio[3266]: info: Using cached record of: tidal://song/11344524
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/11344515
Dec 29 15:55:27 rivoplus volumio[3266]: info: Using cached record of: tidal://song/11344515
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/465528700
Dec 29 15:55:27 rivoplus volumio[3266]: info: Using cached record of: tidal://song/465528700
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/1184888
Dec 29 15:55:27 rivoplus volumio[3266]: info: Using cached record of: tidal://song/1184888
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/1184893
Dec 29 15:55:27 rivoplus volumio[3266]: info: Using cached record of: tidal://song/1184893
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/16024112
Dec 29 15:55:27 rivoplus volumio[3266]: info: Using cached record of: tidal://song/16024112
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/184779289
Dec 29 15:55:27 rivoplus volumio[3266]: info: Using cached record of: tidal://song/184779289
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/16024568
Dec 29 15:55:27 rivoplus volumio[3266]: info: Using cached record of: tidal://song/16024568
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/16026218
Dec 29 15:55:27 rivoplus volumio[3266]: info: Using cached record of: tidal://song/16026218
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/16025816
Dec 29 15:55:27 rivoplus volumio[3266]: info: Using cached record of: tidal://song/16025816
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/243640456
Dec 29 15:55:27 rivoplus volumio[3266]: info: Using cached record of: tidal://song/243640456
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/59673593
Dec 29 15:55:27 rivoplus volumio[3266]: info: Using cached record of: tidal://song/59673593
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/16024566
Dec 29 15:55:27 rivoplus volumio[3266]: info: Using cached record of: tidal://song/16024566
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/1477891
Dec 29 15:55:27 rivoplus volumio[3266]: info: Using cached record of: tidal://song/1477891
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/59962973
Dec 29 15:55:27 rivoplus volumio[3266]: info: Using cached record of: tidal://song/59962973
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/3872597
Dec 29 15:55:27 rivoplus volumio[3266]: info: Using cached record of: tidal://song/3872597
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/1477893
Dec 29 15:55:27 rivoplus volumio[3266]: info: Using cached record of: tidal://song/1477893
Dec 29 15:55:27 rivoplus volumio[3266]: STREAMING PROXY: Client dropped request, destroying
Dec 29 15:55:27 rivoplus volumio[3266]: info:
Dec 29 15:55:27 rivoplus volumio[3266]: ---------------------------- MPD announces state update: player
Dec 29 15:55:27 rivoplus volumio[3266]: info: sendMpdCommand stop took 81 milliseconds
Dec 29 15:55:27 rivoplus volumio[3266]: info: ControllerMpd::getState
Dec 29 15:55:27 rivoplus volumio[3266]: verbose: ControllerMpd::sendMpdCommand status
Dec 29 15:55:27 rivoplus volumio[3266]: info:
Dec 29 15:55:27 rivoplus volumio[3266]: ---------------------------- MPD announces state update: player
Dec 29 15:55:27 rivoplus volumio[3266]: info: ControllerMpd::getState
Dec 29 15:55:27 rivoplus volumio[3266]: verbose: ControllerMpd::sendMpdCommand status
Dec 29 15:55:27 rivoplus volumio[3266]: info:
Dec 29 15:55:27 rivoplus volumio[3266]: ---------------------------- MPD announces state update: player
Dec 29 15:55:27 rivoplus volumio[3266]: info: ControllerMpd::getState
Dec 29 15:55:27 rivoplus volumio[3266]: verbose: ControllerMpd::sendMpdCommand status
Dec 29 15:55:27 rivoplus volumio[3266]: info: sendMpdCommand status took 9 milliseconds
Dec 29 15:55:27 rivoplus volumio[3266]: info: sendMpdCommand status took 9 milliseconds
Dec 29 15:55:27 rivoplus volumio[3266]: info: sendMpdCommand status took 7 milliseconds
Dec 29 15:55:27 rivoplus volumio[3266]: verbose: ControllerMpd::parseState
Dec 29 15:55:27 rivoplus volumio[3266]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 29 15:55:27 rivoplus volumio[3266]: verbose: ControllerMpd::parseState
Dec 29 15:55:27 rivoplus volumio[3266]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 29 15:55:27 rivoplus volumio[3266]: verbose: ControllerMpd::parseState
Dec 29 15:55:27 rivoplus volumio[3266]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 29 15:55:27 rivoplus volumio[3266]: info: sendMpdCommand playlistinfo took 2 milliseconds
Dec 29 15:55:27 rivoplus volumio[3266]: info: sendMpdCommand playlistinfo took 3 milliseconds
Dec 29 15:55:27 rivoplus volumio[3266]: info: sendMpdCommand playlistinfo took 3 milliseconds
Dec 29 15:55:27 rivoplus volumio[3266]: verbose: ControllerMpd::parseTrackInfo
Dec 29 15:55:27 rivoplus volumio[3266]: verbose: ControllerMpd::parseTrackInfo
Dec 29 15:55:27 rivoplus volumio[3266]: verbose: ControllerMpd::parseTrackInfo
Dec 29 15:55:27 rivoplus volumio[3266]: info: ControllerMpd::pushState
Dec 29 15:55:27 rivoplus volumio[3266]: info: CoreCommandRouter::servicePushState
Dec 29 15:55:27 rivoplus volumio[3266]: info: CoreStateMachine::pushState
Dec 29 15:55:27 rivoplus volumio[3266]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 29 15:55:27 rivoplus volumio[3266]: info: CoreCommandRouter::volumioPushState
Dec 29 15:55:27 rivoplus volumio[3266]: info: CorePlayQueue::getTrack 45
Dec 29 15:55:27 rivoplus volumio[3266]: 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":"1477891&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/1477891&soundQuality=HI_RES","trackType":"tidal"}
Dec 29 15:55:27 rivoplus volumio[3266]: verbose: CURRENT POSITION 45
Dec 29 15:55:27 rivoplus volumio[3266]: info: CoreStateMachine::syncState stateService stop
Dec 29 15:55:27 rivoplus volumio[3266]: info: CoreStateMachine::syncState currentStatus stop
Dec 29 15:55:27 rivoplus volumio[3266]: info: CoreStateMachine::pushState
Dec 29 15:55:27 rivoplus volumio[3266]: info: CoreCommandRouter::volumioPushState
Dec 29 15:55:27 rivoplus volumio[3266]: info: No code
Dec 29 15:55:27 rivoplus volumio[3266]: info: CoreStateMachine::pushState
Dec 29 15:55:27 rivoplus volumio[3266]: info: CoreCommandRouter::volumioPushState
Dec 29 15:55:27 rivoplus volumio[3266]: info: ControllerMpd::pushState
Dec 29 15:55:27 rivoplus volumio[3266]: info: CoreCommandRouter::servicePushState
Dec 29 15:55:27 rivoplus volumio[3266]: info: CoreStateMachine::pushState
Dec 29 15:55:27 rivoplus volumio[3266]: info: CoreCommandRouter::volumioPushState
Dec 29 15:55:27 rivoplus volumio[3266]: info: CorePlayQueue::getTrack 45
Dec 29 15:55:27 rivoplus volumio[3266]: 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":"1477891&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/1477891&soundQuality=HI_RES","trackType":"tidal"}
Dec 29 15:55:27 rivoplus volumio[3266]: verbose: CURRENT POSITION 45
Dec 29 15:55:27 rivoplus volumio[3266]: info: CoreStateMachine::syncState stateService stop
Dec 29 15:55:27 rivoplus volumio[3266]: info: CoreStateMachine::syncState currentStatus stop
Dec 29 15:55:27 rivoplus volumio[3266]: info: CoreStateMachine::pushState
Dec 29 15:55:27 rivoplus volumio[3266]: info: CoreCommandRouter::volumioPushState
Dec 29 15:55:27 rivoplus volumio[3266]: info: No code
Dec 29 15:55:27 rivoplus volumio[3266]: info: CoreStateMachine::pushState
Dec 29 15:55:27 rivoplus volumio[3266]: info: CoreCommandRouter::volumioPushState
Dec 29 15:55:27 rivoplus volumio[3266]: info: ControllerMpd::pushState
Dec 29 15:55:27 rivoplus volumio[3266]: info: CoreCommandRouter::servicePushState
Dec 29 15:55:27 rivoplus volumio[3266]: info: CoreStateMachine::pushState
Dec 29 15:55:27 rivoplus volumio[3266]: info: CoreCommandRouter::volumioPushState
Dec 29 15:55:27 rivoplus volumio[3266]: info: CorePlayQueue::getTrack 45
Dec 29 15:55:27 rivoplus volumio[3266]: 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":"1477891&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/1477891&soundQuality=HI_RES","trackType":"tidal"}
Dec 29 15:55:27 rivoplus volumio[3266]: verbose: CURRENT POSITION 45
Dec 29 15:55:27 rivoplus volumio[3266]: info: CoreStateMachine::syncState stateService stop
Dec 29 15:55:27 rivoplus volumio[3266]: info: CoreStateMachine::syncState currentStatus stop
Dec 29 15:55:27 rivoplus volumio[3266]: info: CoreStateMachine::pushState
Dec 29 15:55:27 rivoplus volumio[3266]: info: CoreCommandRouter::volumioPushState
Dec 29 15:55:27 rivoplus volumio[3266]: info: No code
Dec 29 15:55:27 rivoplus volumio[3266]: info: CoreStateMachine::pushState
Dec 29 15:55:27 rivoplus volumio[3266]: info: CoreCommandRouter::volumioPushState
Dec 29 15:55:27 rivoplus volumio[3266]: info: ------------------------------ 136ms
Dec 29 15:55:27 rivoplus volumio[3266]: info: ------------------------------ 134ms
Dec 29 15:55:27 rivoplus volumio[3266]: info: ------------------------------ 134ms
Dec 29 15:55:27 rivoplus volumio[3266]: info: Signalling Playback active due to playback status change
Dec 29 15:55:27 rivoplus volumio[3266]: info: MCU Signalled Playback Inactive
Dec 29 15:55:27 rivoplus volumio[3266]: info: MCU Signalled Playback Active
Dec 29 15:55:27 rivoplus volumio[3266]: info: explodeTIDALUri took 439 milliseconds
Dec 29 15:55:27 rivoplus volumio[3266]: error: TIDAL Browse Error: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [182951917] not found"}
Dec 29 15:55:27 rivoplus volumio[3266]: error: Commandrouter: Cannot explode uri tidal://song/182951917 from service tidal: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [182951917] not found"}
Dec 29 15:55:27 rivoplus volumio[3266]: info: explodeTIDALUri took 440 milliseconds
Dec 29 15:55:27 rivoplus volumio[3266]: error: TIDAL Browse Error: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [182951923] not found"}
Dec 29 15:55:27 rivoplus volumio[3266]: error: Commandrouter: Cannot explode uri tidal://song/182951923 from service tidal: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [182951923] not found"}
Dec 29 15:55:27 rivoplus volumio[3266]: info: explodeTIDALUri took 460 milliseconds
Dec 29 15:55:27 rivoplus volumio[3266]: error: TIDAL Browse Error: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [111491556] not found"}
Dec 29 15:55:27 rivoplus volumio[3266]: error: Commandrouter: Cannot explode uri tidal://song/111491556 from service tidal: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [111491556] not found"}
Dec 29 15:55:27 rivoplus volumio[3266]: info: CoreCommandRouter::volumioPushQueue
Dec 29 15:55:27 rivoplus volumio[3266]: info: CorePlayQueue::saveQueue
Dec 29 15:55:27 rivoplus volumio[3266]: info: CoreStateMachine::updateTrackBlock
Dec 29 15:55:27 rivoplus volumio[3266]: info: CorePlayQueue::getTrackBlock
Dec 29 15:55:27 rivoplus volumio[3266]: info: CoreCommandRouter::volumioPlay
Dec 29 15:55:27 rivoplus volumio[3266]: info: CoreStateMachine::play index 51
Dec 29 15:55:27 rivoplus volumio[3266]: info: CoreStateMachine::setConsumeUpdateService undefined
Dec 29 15:55:27 rivoplus volumio[3266]: info: CoreStateMachine::addQueueItems
Dec 29 15:55:27 rivoplus volumio[3266]: info: CorePlayQueue::addQueueItems
Dec 29 15:55:27 rivoplus volumio[3266]: info: Preload queue cleared
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/341263271
Dec 29 15:55:27 rivoplus volumio[3266]: info: Using cached record of: tidal://song/341263271
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/25652
Dec 29 15:55:27 rivoplus volumio[3266]: info: Using cached record of: tidal://song/25652
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/240475
Dec 29 15:55:27 rivoplus volumio[3266]: info: Using cached record of: tidal://song/240475
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/205781747
Dec 29 15:55:27 rivoplus volumio[3266]: info: Using cached record of: tidal://song/205781747
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/132779700
Dec 29 15:55:27 rivoplus volumio[3266]: info: Using cached record of: tidal://song/132779700
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/16023976
Dec 29 15:55:27 rivoplus volumio[3266]: info: Using cached record of: tidal://song/16023976
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/233502187
Dec 29 15:55:27 rivoplus volumio[3266]: info: Using cached record of: tidal://song/233502187
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/5170460
Dec 29 15:55:27 rivoplus volumio[3266]: info: Using cached record of: tidal://song/5170460
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/125077738
Dec 29 15:55:27 rivoplus volumio[3266]: info: Using cached record of: tidal://song/125077738
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/23941814
Dec 29 15:55:27 rivoplus volumio[3266]: info: Using cached record of: tidal://song/23941814
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/285337586
Dec 29 15:55:27 rivoplus volumio[3266]: info: Using cached record of: tidal://song/285337586
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/291701511
Dec 29 15:55:27 rivoplus volumio[3266]: info: Using cached record of: tidal://song/291701511
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/125079037
Dec 29 15:55:27 rivoplus volumio[3266]: info: Using cached record of: tidal://song/125079037
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/124739692
Dec 29 15:55:27 rivoplus volumio[3266]: info: Using cached record of: tidal://song/124739692
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/16023740
Dec 29 15:55:27 rivoplus volumio[3266]: info: Using cached record of: tidal://song/16023740
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/16023741
Dec 29 15:55:27 rivoplus volumio[3266]: info: Using cached record of: tidal://song/16023741
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/20589793
Dec 29 15:55:27 rivoplus volumio[3266]: info: Using cached record of: tidal://song/20589793
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/20589797
Dec 29 15:55:27 rivoplus volumio[3266]: info: Using cached record of: tidal://song/20589797
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/20589806
Dec 29 15:55:27 rivoplus volumio[3266]: info: Using cached record of: tidal://song/20589806
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/299119014
Dec 29 15:55:27 rivoplus volumio[3266]: info: Using cached record of: tidal://song/299119014
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/25824862
Dec 29 15:55:27 rivoplus volumio[3266]: info: Using cached record of: tidal://song/25824862
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/366453392
Dec 29 15:55:27 rivoplus volumio[3266]: info: Using cached record of: tidal://song/366453392
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/431923387
Dec 29 15:55:27 rivoplus volumio[3266]: info: Using cached record of: tidal://song/431923387
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/324730312
Dec 29 15:55:27 rivoplus volumio[3266]: info: Using cached record of: tidal://song/324730312
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/446085187
Dec 29 15:55:27 rivoplus volumio[3266]: info: Using cached record of: tidal://song/446085187
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/88632568
Dec 29 15:55:27 rivoplus volumio[3266]: info: Using cached record of: tidal://song/88632568
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/180557746
Dec 29 15:55:27 rivoplus volumio[3266]: info: Using cached record of: tidal://song/180557746
Dec 29 15:55:27 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/180557740
Dec 29 15:55:27 rivoplus volumio[3266]: info: Using cached record of: tidal://song/180557740
Dec 29 15:55:27 rivoplus volumio[3266]: info: CoreStateMachine::stop
Dec 29 15:55:27 rivoplus volumio[3266]: info: CoreStateMachine::setConsumeUpdateService undefined
Dec 29 15:55:27 rivoplus volumio[3266]: info: CoreCommandRouter::volumioPushQueue
Dec 29 15:55:27 rivoplus volumio[3266]: info: CorePlayQueue::saveQueue
Dec 29 15:55:27 rivoplus volumio[3266]: info: CoreStateMachine::play index undefined
Dec 29 15:55:27 rivoplus volumio[3266]: info: CoreStateMachine::setConsumeUpdateService undefined
Dec 29 15:55:27 rivoplus volumio[3266]: info: CoreStateMachine::updateTrackBlock
Dec 29 15:55:27 rivoplus volumio[3266]: info: CorePlayQueue::getTrackBlock
Dec 29 15:55:27 rivoplus volumio[3266]: info: CorePlayQueue::getTrack 51
Dec 29 15:55:27 rivoplus volumio[3266]: info: CoreStateMachine::startPlaybackTimer
Dec 29 15:55:27 rivoplus volumio[3266]: info: CorePlayQueue::getTrack 51
Dec 29 15:55:27 rivoplus volumio[3266]: info: [1767020127778] ControllerTidal::clearAddPlayTrack
Dec 29 15:55:27 rivoplus volumio[3266]: info: Getting stream with soundQuality HI_RES
Dec 29 15:55:27 rivoplus volumio[3266]: verbose: ControllerMpd::sendMpdCommand stop
Dec 29 15:55:27 rivoplus volumio[3266]: info: sendMpdCommand stop took 20 milliseconds
Dec 29 15:55:27 rivoplus volumio[3266]: verbose: ControllerMpd::sendMpdCommand clear
Dec 29 15:55:27 rivoplus volumio[3266]: info:
Dec 29 15:55:27 rivoplus volumio[3266]: ---------------------------- MPD announces system playlist update
Dec 29 15:55:27 rivoplus volumio[3266]: info: Ignoring MPD Status Update
Dec 29 15:55:27 rivoplus volumio[3266]: info: sendMpdCommand clear took 6 milliseconds
Dec 29 15:55:27 rivoplus volumio[3266]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/240475&soundQuality=HI_RES"
Dec 29 15:55:27 rivoplus volumio[3266]: info:
Dec 29 15:55:27 rivoplus volumio[3266]: ---------------------------- MPD announces system playlist update
Dec 29 15:55:27 rivoplus volumio[3266]: info: Ignoring MPD Status Update
Dec 29 15:55:27 rivoplus volumio[3266]: info:
Dec 29 15:55:27 rivoplus volumio[3266]: ---------------------------- MPD announces system playlist update
Dec 29 15:55:27 rivoplus volumio[3266]: info: Ignoring MPD Status Update
Dec 29 15:55:27 rivoplus volumio[3266]: error: updateQueue error: null
Dec 29 15:55:27 rivoplus volumio[3266]: info:
Dec 29 15:55:27 rivoplus volumio[3266]: ---------------------------- MPD announces system playlist update
Dec 29 15:55:27 rivoplus volumio[3266]: info: Ignoring MPD Status Update
Dec 29 15:55:27 rivoplus volumio[3266]: info: ------------------------------ 7ms
Dec 29 15:55:27 rivoplus volumio[3266]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/240475&soundQuality=HI_RES" took 5 milliseconds
Dec 29 15:55:27 rivoplus volumio[3266]: info: ------------------------------ 5ms
Dec 29 15:55:27 rivoplus volumio[3266]: info: ------------------------------ 4ms
Dec 29 15:55:27 rivoplus volumio[3266]: info: CoreStateMachine::setConsumeUpdateService mpd
Dec 29 15:55:27 rivoplus volumio[3266]: verbose: ControllerMpd::sendMpdCommand play
Dec 29 15:55:27 rivoplus volumio[3266]: info:
Dec 29 15:55:27 rivoplus volumio[3266]: ---------------------------- MPD announces system playlist update
Dec 29 15:55:27 rivoplus volumio[3266]: info: Ignoring MPD Status Update
Dec 29 15:55:27 rivoplus volumio[3266]: info:
Dec 29 15:55:27 rivoplus volumio[3266]: ---------------------------- MPD announces system playlist update
Dec 29 15:55:27 rivoplus volumio[3266]: info: Ignoring MPD Status Update
Dec 29 15:55:27 rivoplus volumio[3266]: STREAMING PROXY: Handling url /?data=tidal://song/240475&soundQuality=HI_RES
Dec 29 15:55:27 rivoplus volumio[3266]: info: ------------------------------ 9ms
Dec 29 15:55:27 rivoplus volumio[3266]: info: sendMpdCommand play took 8 milliseconds
Dec 29 15:55:27 rivoplus volumio[3266]: info: ------------------------------ 7ms
Dec 29 15:55:27 rivoplus volumio[3266]: info: ------------------------------ 5ms
Dec 29 15:55:27 rivoplus volumio[3266]: info: Executing endpoint getStreamUrltidal
Dec 29 15:55:27 rivoplus volumio[3266]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI
Dec 29 15:55:28 rivoplus volumio[3266]: info: getStreamUrl took 286 milliseconds
Dec 29 15:55:28 rivoplus volumio[3266]: STREAMING PROXY: Got real url: http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQ5NDMzNzcyMWM0NzUwZjUxZTQ2OTA2ZTJlNDI5NGE2Zi5tcDQ/0.flac?token=1767023728~MDg1ZTFiMjM5YTVjMWRkY2JmZTE2YmE0NzkxNzM1N2U5OGQ0ZDU0MA==
Dec 29 15:55:28 rivoplus volumio[3266]: STREAMING PROXY: Response: 200, length: 38394812
Dec 29 15:55:28 rivoplus volumio[3266]: info:
Dec 29 15:55:28 rivoplus volumio[3266]: ---------------------------- MPD announces state update: player
Dec 29 15:55:28 rivoplus volumio[3266]: info: ControllerMpd::getState
Dec 29 15:55:28 rivoplus volumio[3266]: verbose: ControllerMpd::sendMpdCommand status
Dec 29 15:55:28 rivoplus volumio[3266]: info:
Dec 29 15:55:28 rivoplus volumio[3266]: ---------------------------- MPD announces state update: player
Dec 29 15:55:28 rivoplus volumio[3266]: info: ControllerMpd::getState
Dec 29 15:55:28 rivoplus volumio[3266]: verbose: ControllerMpd::sendMpdCommand status
Dec 29 15:55:28 rivoplus volumio[3266]: info:
Dec 29 15:55:28 rivoplus volumio[3266]: ---------------------------- MPD announces state update: player
Dec 29 15:55:28 rivoplus volumio[3266]: info: ControllerMpd::getState
Dec 29 15:55:28 rivoplus volumio[3266]: verbose: ControllerMpd::sendMpdCommand status
Dec 29 15:55:28 rivoplus volumio[3266]: info:
Dec 29 15:55:28 rivoplus volumio[3266]: ---------------------------- MPD announces state update: player
Dec 29 15:55:28 rivoplus volumio[3266]: info: sendMpdCommand status took 74 milliseconds
Dec 29 15:55:28 rivoplus volumio[3266]: info: ControllerMpd::getState
Dec 29 15:55:28 rivoplus volumio[3266]: verbose: ControllerMpd::sendMpdCommand status
Dec 29 15:55:28 rivoplus volumio[3266]: verbose: ControllerMpd::parseState
Dec 29 15:55:28 rivoplus volumio[3266]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 29 15:55:28 rivoplus volumio[3266]: info: sendMpdCommand status took 75 milliseconds
Dec 29 15:55:28 rivoplus volumio[3266]: info: sendMpdCommand status took 74 milliseconds
Dec 29 15:55:28 rivoplus volumio[3266]: verbose: ControllerMpd::parseState
Dec 29 15:55:28 rivoplus volumio[3266]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 29 15:55:28 rivoplus volumio[3266]: verbose: ControllerMpd::parseState
Dec 29 15:55:28 rivoplus volumio[3266]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 29 15:55:28 rivoplus volumio[3266]: info:
Dec 29 15:55:28 rivoplus volumio[3266]: ---------------------------- MPD announces state update: player
Dec 29 15:55:28 rivoplus volumio[3266]: info: ControllerMpd::getState
Dec 29 15:55:28 rivoplus volumio[3266]: verbose: ControllerMpd::sendMpdCommand status
Dec 29 15:55:28 rivoplus volumio[3266]: info:
Dec 29 15:55:28 rivoplus volumio[3266]: ---------------------------- MPD announces state update: player
Dec 29 15:55:28 rivoplus volumio[3266]: info: ControllerMpd::getState
Dec 29 15:55:28 rivoplus volumio[3266]: verbose: ControllerMpd::sendMpdCommand status
Dec 29 15:55:28 rivoplus volumio[3266]: info: sendMpdCommand status took 17 milliseconds
Dec 29 15:55:28 rivoplus volumio[3266]: info: sendMpdCommand playlistinfo took 17 milliseconds
Dec 29 15:55:28 rivoplus volumio[3266]: info: sendMpdCommand playlistinfo took 15 milliseconds
Dec 29 15:55:28 rivoplus volumio[3266]: info: sendMpdCommand playlistinfo took 14 milliseconds
Dec 29 15:55:28 rivoplus volumio[3266]: info: sendMpdCommand status took 13 milliseconds
Dec 29 15:55:28 rivoplus volumio[3266]: info: sendMpdCommand status took 5 milliseconds
Dec 29 15:55:28 rivoplus volumio[3266]: verbose: ControllerMpd::parseState
Dec 29 15:55:28 rivoplus volumio[3266]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 29 15:55:28 rivoplus volumio[3266]: verbose: ControllerMpd::parseTrackInfo
Dec 29 15:55:28 rivoplus volumio[3266]: verbose: ControllerMpd::parseTrackInfo
Dec 29 15:55:28 rivoplus volumio[3266]: verbose: ControllerMpd::parseTrackInfo
Dec 29 15:55:28 rivoplus volumio[3266]: verbose: ControllerMpd::parseState
Dec 29 15:55:28 rivoplus volumio[3266]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 29 15:55:28 rivoplus volumio[3266]: verbose: ControllerMpd::parseState
Dec 29 15:55:28 rivoplus volumio[3266]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 29 15:55:28 rivoplus volumio[3266]: info: ControllerMpd::pushState
Dec 29 15:55:28 rivoplus volumio[3266]: info: CoreCommandRouter::servicePushState
Dec 29 15:55:28 rivoplus volumio[3266]: info: CorePlayQueue::getTrack 51
Dec 29 15:55:28 rivoplus volumio[3266]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":347,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"240475&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/240475&soundQuality=HI_RES","trackType":"tidal"}
Dec 29 15:55:28 rivoplus volumio[3266]: verbose: CURRENT POSITION 51
Dec 29 15:55:28 rivoplus volumio[3266]: info: CoreStateMachine::syncState stateService play
Dec 29 15:55:28 rivoplus volumio[3266]: info: CoreStateMachine::syncState currentStatus stop
Dec 29 15:55:28 rivoplus volumio[3266]: info: ControllerMpd::pushState
Dec 29 15:55:28 rivoplus volumio[3266]: info: CoreCommandRouter::servicePushState
Dec 29 15:55:28 rivoplus volumio[3266]: info: CorePlayQueue::getTrack 51
Dec 29 15:55:28 rivoplus volumio[3266]: verbose: STATE SERVICE {"status":"play","position":0,"seek":68,"duration":347,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"240475&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/240475&soundQuality=HI_RES","trackType":"tidal"}
Dec 29 15:55:28 rivoplus volumio[3266]: verbose: CURRENT POSITION 51
Dec 29 15:55:28 rivoplus volumio[3266]: info: CoreStateMachine::syncState stateService play
Dec 29 15:55:28 rivoplus volumio[3266]: info: CoreStateMachine::syncState currentStatus play
Dec 29 15:55:28 rivoplus volumio[3266]: info: Received an update from plugin. extracting info from payload
Dec 29 15:55:28 rivoplus volumio[3266]: info: CoreStateMachine::pushState
Dec 29 15:55:28 rivoplus volumio[3266]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 29 15:55:28 rivoplus volumio[3266]: info: CoreCommandRouter::volumioPushState
Dec 29 15:55:28 rivoplus volumio[3266]: info: CoreStateMachine::pushState
Dec 29 15:55:28 rivoplus volumio[3266]: info: CoreCommandRouter::volumioPushState
Dec 29 15:55:28 rivoplus volumio[3266]: info: ControllerMpd::pushState
Dec 29 15:55:28 rivoplus volumio[3266]: info: CoreCommandRouter::servicePushState
Dec 29 15:55:28 rivoplus volumio[3266]: info: CorePlayQueue::getTrack 51
Dec 29 15:55:28 rivoplus volumio[3266]: verbose: STATE SERVICE {"status":"play","position":0,"seek":91,"duration":347,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"400 Kbps","isStreaming":false,"title":"240475&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/240475&soundQuality=HI_RES","trackType":"tidal"}
Dec 29 15:55:28 rivoplus volumio[3266]: verbose: CURRENT POSITION 51
Dec 29 15:55:28 rivoplus volumio[3266]: info: CoreStateMachine::syncState stateService play
Dec 29 15:55:28 rivoplus volumio[3266]: info: CoreStateMachine::syncState currentStatus play
Dec 29 15:55:28 rivoplus volumio[3266]: info: Received an update from plugin. extracting info from payload
Dec 29 15:55:28 rivoplus volumio[3266]: info: CoreStateMachine::pushState
Dec 29 15:55:28 rivoplus volumio[3266]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 29 15:55:28 rivoplus volumio[3266]: info: CoreCommandRouter::volumioPushState
Dec 29 15:55:28 rivoplus volumio[3266]: info: CoreStateMachine::pushState
Dec 29 15:55:28 rivoplus volumio[3266]: info: CoreCommandRouter::volumioPushState
Dec 29 15:55:28 rivoplus volumio[3266]: info: ------------------------------ 145ms
Dec 29 15:55:28 rivoplus volumio[3266]: info: ------------------------------ 204ms
Dec 29 15:55:28 rivoplus volumio[3266]: info: ------------------------------ 204ms
Dec 29 15:55:28 rivoplus volumio[3266]: info: sendMpdCommand playlistinfo took 114 milliseconds
Dec 29 15:55:28 rivoplus volumio[3266]: info: sendMpdCommand playlistinfo took 113 milliseconds
Dec 29 15:55:28 rivoplus volumio[3266]: info: sendMpdCommand playlistinfo took 113 milliseconds
Dec 29 15:55:28 rivoplus volumio[3266]: verbose: ControllerMpd::parseTrackInfo
Dec 29 15:55:28 rivoplus volumio[3266]: verbose: ControllerMpd::parseTrackInfo
Dec 29 15:55:28 rivoplus volumio[3266]: verbose: ControllerMpd::parseTrackInfo
Dec 29 15:55:28 rivoplus volumio[3266]: info: ControllerMpd::pushState
Dec 29 15:55:28 rivoplus volumio[3266]: info: CoreCommandRouter::servicePushState
Dec 29 15:55:28 rivoplus volumio[3266]: info: CorePlayQueue::getTrack 51
Dec 29 15:55:28 rivoplus volumio[3266]: verbose: STATE SERVICE {"status":"play","position":0,"seek":114,"duration":347,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"400 Kbps","isStreaming":false,"title":"240475&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/240475&soundQuality=HI_RES","trackType":"tidal"}
Dec 29 15:55:28 rivoplus volumio[3266]: verbose: CURRENT POSITION 51
Dec 29 15:55:28 rivoplus volumio[3266]: info: CoreStateMachine::syncState stateService play
Dec 29 15:55:28 rivoplus volumio[3266]: info: CoreStateMachine::syncState currentStatus play
Dec 29 15:55:28 rivoplus volumio[3266]: info: Received an update from plugin. extracting info from payload
Dec 29 15:55:28 rivoplus volumio[3266]: info: CoreStateMachine::pushState
Dec 29 15:55:28 rivoplus volumio[3266]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 29 15:55:28 rivoplus volumio[3266]: info: CoreCommandRouter::volumioPushState
Dec 29 15:55:28 rivoplus volumio[3266]: info: CoreStateMachine::pushState
Dec 29 15:55:28 rivoplus volumio[3266]: info: CoreCommandRouter::volumioPushState
Dec 29 15:55:28 rivoplus volumio[3266]: info: ControllerMpd::pushState
Dec 29 15:55:28 rivoplus volumio[3266]: info: CoreCommandRouter::servicePushState
Dec 29 15:55:28 rivoplus volumio[3266]: info: CorePlayQueue::getTrack 51
Dec 29 15:55:28 rivoplus volumio[3266]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":347,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"619 Kbps","isStreaming":false,"title":"240475&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/240475&soundQuality=HI_RES","trackType":"tidal"}
Dec 29 15:55:28 rivoplus volumio[3266]: verbose: CURRENT POSITION 51
Dec 29 15:55:28 rivoplus volumio[3266]: info: CoreStateMachine::syncState stateService play
Dec 29 15:55:28 rivoplus volumio[3266]: info: CoreStateMachine::syncState currentStatus play
Dec 29 15:55:28 rivoplus volumio[3266]: info: Received an update from plugin. extracting info from payload
Dec 29 15:55:28 rivoplus volumio[3266]: info: CoreStateMachine::pushState
Dec 29 15:55:28 rivoplus volumio[3266]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 29 15:55:28 rivoplus volumio[3266]: info: CoreCommandRouter::volumioPushState
Dec 29 15:55:28 rivoplus volumio[3266]: info: CoreStateMachine::pushState
Dec 29 15:55:28 rivoplus volumio[3266]: info: CoreCommandRouter::volumioPushState
Dec 29 15:55:28 rivoplus volumio[3266]: info: ControllerMpd::pushState
Dec 29 15:55:28 rivoplus volumio[3266]: info: CoreCommandRouter::servicePushState
Dec 29 15:55:28 rivoplus volumio[3266]: info: CorePlayQueue::getTrack 51
Dec 29 15:55:28 rivoplus volumio[3266]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":347,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"619 Kbps","isStreaming":false,"title":"240475&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/240475&soundQuality=HI_RES","trackType":"tidal"}
Dec 29 15:55:28 rivoplus volumio[3266]: verbose: CURRENT POSITION 51
Dec 29 15:55:28 rivoplus volumio[3266]: info: CoreStateMachine::syncState stateService play
Dec 29 15:55:28 rivoplus volumio[3266]: info: CoreStateMachine::syncState currentStatus play
Dec 29 15:55:28 rivoplus volumio[3266]: info: Received an update from plugin. extracting info from payload
Dec 29 15:55:28 rivoplus volumio[3266]: info: CoreStateMachine::pushState
Dec 29 15:55:28 rivoplus volumio[3266]: info: CoreCommandRouter::volumioPushState
Dec 29 15:55:28 rivoplus volumio[3266]: info: CoreStateMachine::pushState
Dec 29 15:55:28 rivoplus volumio[3266]: info: CoreCommandRouter::volumioPushState
Dec 29 15:55:28 rivoplus volumio[3266]: info: ------------------------------ 237ms
Dec 29 15:55:28 rivoplus volumio[3266]: info: ------------------------------ 230ms
Dec 29 15:55:28 rivoplus volumio[3266]: info: ------------------------------ 224ms
Dec 29 15:55:31 rivoplus volumio[3266]: STREAMING PROXY: Client dropped request, destroying
Dec 29 15:55:35 rivoplus volumio[3266]: info: Preload queue cleared
Dec 29 15:55:35 rivoplus volumio[3266]: info: CoreCommandRouter::volumioReplaceandPlayItems
Dec 29 15:55:35 rivoplus volumio[3266]: info: CoreStateMachine::ClearQueue
Dec 29 15:55:35 rivoplus volumio[3266]: info: CoreStateMachine::stop
Dec 29 15:55:35 rivoplus volumio[3266]: info: CoreStateMachine::setConsumeUpdateService undefined
Dec 29 15:55:35 rivoplus volumio[3266]: info: CoreStateMachine::stPlaybackTimer
Dec 29 15:55:35 rivoplus volumio[3266]: info: CoreStateMachine::updateTrackBlock
Dec 29 15:55:35 rivoplus volumio[3266]: info: CorePlayQueue::getTrackBlock
Dec 29 15:55:35 rivoplus volumio[3266]: info: CoreStateMachine::pushState
Dec 29 15:55:35 rivoplus volumio[3266]: info: CorePlayQueue::getTrack 51
Dec 29 15:55:35 rivoplus volumio[3266]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 29 15:55:35 rivoplus volumio[3266]: info: CoreCommandRouter::volumioPushState
Dec 29 15:55:35 rivoplus volumio[3266]: info: CoreStateMachine::serviceStop
Dec 29 15:55:35 rivoplus volumio[3266]: info: CorePlayQueue::getTrack 51
Dec 29 15:55:35 rivoplus volumio[3266]: info: CoreCommandRouter::serviceStop
Dec 29 15:55:35 rivoplus volumio[3266]: info: [1767020135048] ControllerTidal::stop
Dec 29 15:55:35 rivoplus volumio[3266]: info: CoreStateMachine::setConsumeUpdateService mpd
Dec 29 15:55:35 rivoplus volumio[3266]: info: ControllerMpd::stop
Dec 29 15:55:35 rivoplus volumio[3266]: verbose: ControllerMpd::sendMpdCommand stop
Dec 29 15:55:35 rivoplus volumio[3266]: info: CorePlayQueue::clearPlayQueue
Dec 29 15:55:35 rivoplus volumio[3266]: info: CorePlayQueue::saveQueue
Dec 29 15:55:35 rivoplus volumio[3266]: info: CoreCommandRouter::volumioPushQueue
Dec 29 15:55:35 rivoplus volumio[3266]: info: CoreStateMachine::addQueueItems
Dec 29 15:55:35 rivoplus volumio[3266]: info: CorePlayQueue::addQueueItems
Dec 29 15:55:35 rivoplus volumio[3266]: info: Preload queue cleared
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/16024087
Dec 29 15:55:35 rivoplus volumio[3266]: info: Using cached record of: tidal://song/16024087
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/99920827
Dec 29 15:55:35 rivoplus volumio[3266]: info: Using cached record of: tidal://song/99920827
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/68988294
Dec 29 15:55:35 rivoplus volumio[3266]: info: Using cached record of: tidal://song/68988294
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/4167367
Dec 29 15:55:35 rivoplus volumio[3266]: info: Using cached record of: tidal://song/4167367
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/396060895
Dec 29 15:55:35 rivoplus volumio[3266]: info: Using cached record of: tidal://song/396060895
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/396060905
Dec 29 15:55:35 rivoplus volumio[3266]: info: Using cached record of: tidal://song/396060905
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/396060897
Dec 29 15:55:35 rivoplus volumio[3266]: info: Using cached record of: tidal://song/396060897
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/396060900
Dec 29 15:55:35 rivoplus volumio[3266]: info: Using cached record of: tidal://song/396060900
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/266261642
Dec 29 15:55:35 rivoplus volumio[3266]: info: Using cached record of: tidal://song/266261642
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/96899507
Dec 29 15:55:35 rivoplus volumio[3266]: info: Using cached record of: tidal://song/96899507
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/123150061
Dec 29 15:55:35 rivoplus volumio[3266]: info: Using cached record of: tidal://song/123150061
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/182951917
Dec 29 15:55:35 rivoplus volumio[3266]: info: Exploding uri tidal://song/182951917 in service tidal
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/182951923
Dec 29 15:55:35 rivoplus volumio[3266]: info: Exploding uri tidal://song/182951923 in service tidal
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/175542203
Dec 29 15:55:35 rivoplus volumio[3266]: info: Using cached record of: tidal://song/175542203
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/23567300
Dec 29 15:55:35 rivoplus volumio[3266]: info: Using cached record of: tidal://song/23567300
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/51017424
Dec 29 15:55:35 rivoplus volumio[3266]: info: Using cached record of: tidal://song/51017424
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/51017425
Dec 29 15:55:35 rivoplus volumio[3266]: info: Using cached record of: tidal://song/51017425
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/111491556
Dec 29 15:55:35 rivoplus volumio[3266]: info: Exploding uri tidal://song/111491556 in service tidal
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/88393483
Dec 29 15:55:35 rivoplus volumio[3266]: info: Using cached record of: tidal://song/88393483
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/71882758
Dec 29 15:55:35 rivoplus volumio[3266]: info: Using cached record of: tidal://song/71882758
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/142841005
Dec 29 15:55:35 rivoplus volumio[3266]: info: Using cached record of: tidal://song/142841005
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/172902026
Dec 29 15:55:35 rivoplus volumio[3266]: info: Using cached record of: tidal://song/172902026
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/174184210
Dec 29 15:55:35 rivoplus volumio[3266]: info: Using cached record of: tidal://song/174184210
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/6803518
Dec 29 15:55:35 rivoplus volumio[3266]: info: Using cached record of: tidal://song/6803518
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/6803520
Dec 29 15:55:35 rivoplus volumio[3266]: info: Using cached record of: tidal://song/6803520
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/16024089
Dec 29 15:55:35 rivoplus volumio[3266]: info: Using cached record of: tidal://song/16024089
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/206092212
Dec 29 15:55:35 rivoplus volumio[3266]: info: Using cached record of: tidal://song/206092212
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/206092214
Dec 29 15:55:35 rivoplus volumio[3266]: info: Using cached record of: tidal://song/206092214
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/18057959
Dec 29 15:55:35 rivoplus volumio[3266]: info: Using cached record of: tidal://song/18057959
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/17715072
Dec 29 15:55:35 rivoplus volumio[3266]: info: Using cached record of: tidal://song/17715072
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/87252049
Dec 29 15:55:35 rivoplus volumio[3266]: info: Using cached record of: tidal://song/87252049
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/202266421
Dec 29 15:55:35 rivoplus volumio[3266]: info: Using cached record of: tidal://song/202266421
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/257767340
Dec 29 15:55:35 rivoplus volumio[3266]: info: Using cached record of: tidal://song/257767340
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/257767341
Dec 29 15:55:35 rivoplus volumio[3266]: info: Using cached record of: tidal://song/257767341
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/257767342
Dec 29 15:55:35 rivoplus volumio[3266]: info: Using cached record of: tidal://song/257767342
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/11344524
Dec 29 15:55:35 rivoplus volumio[3266]: info: Using cached record of: tidal://song/11344524
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/11344515
Dec 29 15:55:35 rivoplus volumio[3266]: info: Using cached record of: tidal://song/11344515
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/465528700
Dec 29 15:55:35 rivoplus volumio[3266]: info: Using cached record of: tidal://song/465528700
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/1184888
Dec 29 15:55:35 rivoplus volumio[3266]: info: Using cached record of: tidal://song/1184888
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/1184893
Dec 29 15:55:35 rivoplus volumio[3266]: info: Using cached record of: tidal://song/1184893
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/16024112
Dec 29 15:55:35 rivoplus volumio[3266]: info: Using cached record of: tidal://song/16024112
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/184779289
Dec 29 15:55:35 rivoplus volumio[3266]: info: Using cached record of: tidal://song/184779289
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/16024568
Dec 29 15:55:35 rivoplus volumio[3266]: info: Using cached record of: tidal://song/16024568
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/16026218
Dec 29 15:55:35 rivoplus volumio[3266]: info: Using cached record of: tidal://song/16026218
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/16025816
Dec 29 15:55:35 rivoplus volumio[3266]: info: Using cached record of: tidal://song/16025816
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/243640456
Dec 29 15:55:35 rivoplus volumio[3266]: info: Using cached record of: tidal://song/243640456
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/59673593
Dec 29 15:55:35 rivoplus volumio[3266]: info: Using cached record of: tidal://song/59673593
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/16024566
Dec 29 15:55:35 rivoplus volumio[3266]: info: Using cached record of: tidal://song/16024566
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/1477891
Dec 29 15:55:35 rivoplus volumio[3266]: info: Using cached record of: tidal://song/1477891
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/59962973
Dec 29 15:55:35 rivoplus volumio[3266]: info: Using cached record of: tidal://song/59962973
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/3872597
Dec 29 15:55:35 rivoplus volumio[3266]: info: Using cached record of: tidal://song/3872597
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/1477893
Dec 29 15:55:35 rivoplus volumio[3266]: info: Using cached record of: tidal://song/1477893
Dec 29 15:55:35 rivoplus volumio[3266]: info:
Dec 29 15:55:35 rivoplus volumio[3266]: ---------------------------- MPD announces state update: player
Dec 29 15:55:35 rivoplus volumio[3266]: info: sendMpdCommand stop took 85 milliseconds
Dec 29 15:55:35 rivoplus volumio[3266]: info: ControllerMpd::getState
Dec 29 15:55:35 rivoplus volumio[3266]: verbose: ControllerMpd::sendMpdCommand status
Dec 29 15:55:35 rivoplus volumio[3266]: info:
Dec 29 15:55:35 rivoplus volumio[3266]: ---------------------------- MPD announces state update: player
Dec 29 15:55:35 rivoplus volumio[3266]: info: ControllerMpd::getState
Dec 29 15:55:35 rivoplus volumio[3266]: verbose: ControllerMpd::sendMpdCommand status
Dec 29 15:55:35 rivoplus volumio[3266]: info:
Dec 29 15:55:35 rivoplus volumio[3266]: ---------------------------- MPD announces state update: player
Dec 29 15:55:35 rivoplus volumio[3266]: info: ControllerMpd::getState
Dec 29 15:55:35 rivoplus volumio[3266]: verbose: ControllerMpd::sendMpdCommand status
Dec 29 15:55:35 rivoplus volumio[3266]: info: sendMpdCommand status took 4 milliseconds
Dec 29 15:55:35 rivoplus volumio[3266]: info: sendMpdCommand status took 3 milliseconds
Dec 29 15:55:35 rivoplus volumio[3266]: info: sendMpdCommand status took 2 milliseconds
Dec 29 15:55:35 rivoplus volumio[3266]: verbose: ControllerMpd::parseState
Dec 29 15:55:35 rivoplus volumio[3266]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 29 15:55:35 rivoplus volumio[3266]: verbose: ControllerMpd::parseState
Dec 29 15:55:35 rivoplus volumio[3266]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 29 15:55:35 rivoplus volumio[3266]: verbose: ControllerMpd::parseState
Dec 29 15:55:35 rivoplus volumio[3266]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 29 15:55:35 rivoplus volumio[3266]: info: sendMpdCommand playlistinfo took 2 milliseconds
Dec 29 15:55:35 rivoplus volumio[3266]: info: sendMpdCommand playlistinfo took 2 milliseconds
Dec 29 15:55:35 rivoplus volumio[3266]: verbose: ControllerMpd::parseTrackInfo
Dec 29 15:55:35 rivoplus volumio[3266]: verbose: ControllerMpd::parseTrackInfo
Dec 29 15:55:35 rivoplus volumio[3266]: verbose: ControllerMpd::parseTrackInfo
Dec 29 15:55:35 rivoplus volumio[3266]: info: ControllerMpd::pushState
Dec 29 15:55:35 rivoplus volumio[3266]: info: CoreCommandRouter::servicePushState
Dec 29 15:55:35 rivoplus volumio[3266]: info: CoreStateMachine::pushState
Dec 29 15:55:35 rivoplus volumio[3266]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 29 15:55:35 rivoplus volumio[3266]: info: CoreCommandRouter::volumioPushState
Dec 29 15:55:35 rivoplus volumio[3266]: info: CorePlayQueue::getTrack 51
Dec 29 15:55:35 rivoplus volumio[3266]: 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":"240475&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/240475&soundQuality=HI_RES","trackType":"tidal"}
Dec 29 15:55:35 rivoplus volumio[3266]: verbose: CURRENT POSITION 51
Dec 29 15:55:35 rivoplus volumio[3266]: info: CoreStateMachine::syncState stateService stop
Dec 29 15:55:35 rivoplus volumio[3266]: info: CoreStateMachine::syncState currentStatus stop
Dec 29 15:55:35 rivoplus volumio[3266]: info: CoreStateMachine::pushState
Dec 29 15:55:35 rivoplus volumio[3266]: info: CoreCommandRouter::volumioPushState
Dec 29 15:55:35 rivoplus volumio[3266]: info: No code
Dec 29 15:55:35 rivoplus volumio[3266]: info: CoreStateMachine::pushState
Dec 29 15:55:35 rivoplus volumio[3266]: info: CoreCommandRouter::volumioPushState
Dec 29 15:55:35 rivoplus volumio[3266]: info: ControllerMpd::pushState
Dec 29 15:55:35 rivoplus volumio[3266]: info: CoreCommandRouter::servicePushState
Dec 29 15:55:35 rivoplus volumio[3266]: info: CoreStateMachine::pushState
Dec 29 15:55:35 rivoplus volumio[3266]: info: CoreCommandRouter::volumioPushState
Dec 29 15:55:35 rivoplus volumio[3266]: info: CorePlayQueue::getTrack 51
Dec 29 15:55:35 rivoplus volumio[3266]: 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":"240475&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/240475&soundQuality=HI_RES","trackType":"tidal"}
Dec 29 15:55:35 rivoplus volumio[3266]: verbose: CURRENT POSITION 51
Dec 29 15:55:35 rivoplus volumio[3266]: info: CoreStateMachine::syncState stateService stop
Dec 29 15:55:35 rivoplus volumio[3266]: info: CoreStateMachine::syncState currentStatus stop
Dec 29 15:55:35 rivoplus volumio[3266]: info: CoreStateMachine::pushState
Dec 29 15:55:35 rivoplus volumio[3266]: info: CoreCommandRouter::volumioPushState
Dec 29 15:55:35 rivoplus volumio[3266]: info: No code
Dec 29 15:55:35 rivoplus volumio[3266]: info: CoreStateMachine::pushState
Dec 29 15:55:35 rivoplus volumio[3266]: info: CoreCommandRouter::volumioPushState
Dec 29 15:55:35 rivoplus volumio[3266]: info: ControllerMpd::pushState
Dec 29 15:55:35 rivoplus volumio[3266]: info: CoreCommandRouter::servicePushState
Dec 29 15:55:35 rivoplus volumio[3266]: info: CoreStateMachine::pushState
Dec 29 15:55:35 rivoplus volumio[3266]: info: CoreCommandRouter::volumioPushState
Dec 29 15:55:35 rivoplus volumio[3266]: info: CorePlayQueue::getTrack 51
Dec 29 15:55:35 rivoplus volumio[3266]: 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":"240475&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/240475&soundQuality=HI_RES","trackType":"tidal"}
Dec 29 15:55:35 rivoplus volumio[3266]: verbose: CURRENT POSITION 51
Dec 29 15:55:35 rivoplus volumio[3266]: info: CoreStateMachine::syncState stateService stop
Dec 29 15:55:35 rivoplus volumio[3266]: info: CoreStateMachine::syncState currentStatus stop
Dec 29 15:55:35 rivoplus volumio[3266]: info: CoreStateMachine::pushState
Dec 29 15:55:35 rivoplus volumio[3266]: info: CoreCommandRouter::volumioPushState
Dec 29 15:55:35 rivoplus volumio[3266]: info: No code
Dec 29 15:55:35 rivoplus volumio[3266]: info: CoreStateMachine::pushState
Dec 29 15:55:35 rivoplus volumio[3266]: info: CoreCommandRouter::volumioPushState
Dec 29 15:55:35 rivoplus volumio[3266]: info: ------------------------------ 121ms
Dec 29 15:55:35 rivoplus volumio[3266]: info: ------------------------------ 121ms
Dec 29 15:55:35 rivoplus volumio[3266]: info: ------------------------------ 121ms
Dec 29 15:55:35 rivoplus volumio[3266]: info: Signalling Playback active due to playback status change
Dec 29 15:55:35 rivoplus volumio[3266]: info: MCU Signalled Playback Inactive
Dec 29 15:55:35 rivoplus volumio[3266]: info: MCU Signalled Playback Active
Dec 29 15:55:35 rivoplus volumio[3266]: info: explodeTIDALUri took 396 milliseconds
Dec 29 15:55:35 rivoplus volumio[3266]: error: TIDAL Browse Error: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [182951917] not found"}
Dec 29 15:55:35 rivoplus volumio[3266]: error: Commandrouter: Cannot explode uri tidal://song/182951917 from service tidal: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [182951917] not found"}
Dec 29 15:55:35 rivoplus volumio[3266]: info: explodeTIDALUri took 399 milliseconds
Dec 29 15:55:35 rivoplus volumio[3266]: error: TIDAL Browse Error: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [182951923] not found"}
Dec 29 15:55:35 rivoplus volumio[3266]: error: Commandrouter: Cannot explode uri tidal://song/182951923 from service tidal: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [182951923] not found"}
Dec 29 15:55:35 rivoplus volumio[3266]: info: explodeTIDALUri took 435 milliseconds
Dec 29 15:55:35 rivoplus volumio[3266]: error: TIDAL Browse Error: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [111491556] not found"}
Dec 29 15:55:35 rivoplus volumio[3266]: error: Commandrouter: Cannot explode uri tidal://song/111491556 from service tidal: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [111491556] not found"}
Dec 29 15:55:35 rivoplus volumio[3266]: info: CoreCommandRouter::volumioPushQueue
Dec 29 15:55:35 rivoplus volumio[3266]: info: CorePlayQueue::saveQueue
Dec 29 15:55:35 rivoplus volumio[3266]: info: CoreStateMachine::updateTrackBlock
Dec 29 15:55:35 rivoplus volumio[3266]: info: CorePlayQueue::getTrackBlock
Dec 29 15:55:35 rivoplus volumio[3266]: info: CoreCommandRouter::volumioPlay
Dec 29 15:55:35 rivoplus volumio[3266]: info: CoreStateMachine::play index 51
Dec 29 15:55:35 rivoplus volumio[3266]: info: CoreStateMachine::setConsumeUpdateService undefined
Dec 29 15:55:35 rivoplus volumio[3266]: info: CoreStateMachine::addQueueItems
Dec 29 15:55:35 rivoplus volumio[3266]: info: CorePlayQueue::addQueueItems
Dec 29 15:55:35 rivoplus volumio[3266]: info: Preload queue cleared
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/341263271
Dec 29 15:55:35 rivoplus volumio[3266]: info: Using cached record of: tidal://song/341263271
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/25652
Dec 29 15:55:35 rivoplus volumio[3266]: info: Using cached record of: tidal://song/25652
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/240475
Dec 29 15:55:35 rivoplus volumio[3266]: info: Using cached record of: tidal://song/240475
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/205781747
Dec 29 15:55:35 rivoplus volumio[3266]: info: Using cached record of: tidal://song/205781747
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/132779700
Dec 29 15:55:35 rivoplus volumio[3266]: info: Using cached record of: tidal://song/132779700
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/16023976
Dec 29 15:55:35 rivoplus volumio[3266]: info: Using cached record of: tidal://song/16023976
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/233502187
Dec 29 15:55:35 rivoplus volumio[3266]: info: Using cached record of: tidal://song/233502187
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/5170460
Dec 29 15:55:35 rivoplus volumio[3266]: info: Using cached record of: tidal://song/5170460
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/125077738
Dec 29 15:55:35 rivoplus volumio[3266]: info: Using cached record of: tidal://song/125077738
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/23941814
Dec 29 15:55:35 rivoplus volumio[3266]: info: Using cached record of: tidal://song/23941814
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/285337586
Dec 29 15:55:35 rivoplus volumio[3266]: info: Using cached record of: tidal://song/285337586
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/291701511
Dec 29 15:55:35 rivoplus volumio[3266]: info: Using cached record of: tidal://song/291701511
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/125079037
Dec 29 15:55:35 rivoplus volumio[3266]: info: Using cached record of: tidal://song/125079037
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/124739692
Dec 29 15:55:35 rivoplus volumio[3266]: info: Using cached record of: tidal://song/124739692
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/16023740
Dec 29 15:55:35 rivoplus volumio[3266]: info: Using cached record of: tidal://song/16023740
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/16023741
Dec 29 15:55:35 rivoplus volumio[3266]: info: Using cached record of: tidal://song/16023741
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/20589793
Dec 29 15:55:35 rivoplus volumio[3266]: info: Using cached record of: tidal://song/20589793
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/20589797
Dec 29 15:55:35 rivoplus volumio[3266]: info: Using cached record of: tidal://song/20589797
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/20589806
Dec 29 15:55:35 rivoplus volumio[3266]: info: Using cached record of: tidal://song/20589806
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/299119014
Dec 29 15:55:35 rivoplus volumio[3266]: info: Using cached record of: tidal://song/299119014
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/25824862
Dec 29 15:55:35 rivoplus volumio[3266]: info: Using cached record of: tidal://song/25824862
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/366453392
Dec 29 15:55:35 rivoplus volumio[3266]: info: Using cached record of: tidal://song/366453392
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/431923387
Dec 29 15:55:35 rivoplus volumio[3266]: info: Using cached record of: tidal://song/431923387
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/324730312
Dec 29 15:55:35 rivoplus volumio[3266]: info: Using cached record of: tidal://song/324730312
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/446085187
Dec 29 15:55:35 rivoplus volumio[3266]: info: Using cached record of: tidal://song/446085187
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/88632568
Dec 29 15:55:35 rivoplus volumio[3266]: info: Using cached record of: tidal://song/88632568
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/180557746
Dec 29 15:55:35 rivoplus volumio[3266]: info: Using cached record of: tidal://song/180557746
Dec 29 15:55:35 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/180557740
Dec 29 15:55:35 rivoplus volumio[3266]: info: Using cached record of: tidal://song/180557740
Dec 29 15:55:35 rivoplus volumio[3266]: info: CoreStateMachine::stop
Dec 29 15:55:35 rivoplus volumio[3266]: info: CoreStateMachine::setConsumeUpdateService undefined
Dec 29 15:55:35 rivoplus volumio[3266]: info: CoreCommandRouter::volumioPushQueue
Dec 29 15:55:35 rivoplus volumio[3266]: info: CorePlayQueue::saveQueue
Dec 29 15:55:35 rivoplus volumio[3266]: info: CoreStateMachine::play index undefined
Dec 29 15:55:35 rivoplus volumio[3266]: info: CoreStateMachine::setConsumeUpdateService undefined
Dec 29 15:55:35 rivoplus volumio[3266]: info: CoreStateMachine::updateTrackBlock
Dec 29 15:55:35 rivoplus volumio[3266]: info: CorePlayQueue::getTrackBlock
Dec 29 15:55:35 rivoplus volumio[3266]: info: CorePlayQueue::getTrack 51
Dec 29 15:55:35 rivoplus volumio[3266]: info: CoreStateMachine::startPlaybackTimer
Dec 29 15:55:35 rivoplus volumio[3266]: info: CorePlayQueue::getTrack 51
Dec 29 15:55:35 rivoplus volumio[3266]: info: [1767020135584] ControllerTidal::clearAddPlayTrack
Dec 29 15:55:35 rivoplus volumio[3266]: info: Getting stream with soundQuality HI_RES
Dec 29 15:55:35 rivoplus volumio[3266]: verbose: ControllerMpd::sendMpdCommand stop
Dec 29 15:55:35 rivoplus volumio[3266]: info: sendMpdCommand stop took 21 milliseconds
Dec 29 15:55:35 rivoplus volumio[3266]: verbose: ControllerMpd::sendMpdCommand clear
Dec 29 15:55:35 rivoplus volumio[3266]: info:
Dec 29 15:55:35 rivoplus volumio[3266]: ---------------------------- MPD announces system playlist update
Dec 29 15:55:35 rivoplus volumio[3266]: info: Ignoring MPD Status Update
Dec 29 15:55:35 rivoplus volumio[3266]: info: sendMpdCommand clear took 6 milliseconds
Dec 29 15:55:35 rivoplus volumio[3266]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/240475&soundQuality=HI_RES"
Dec 29 15:55:35 rivoplus volumio[3266]: info:
Dec 29 15:55:35 rivoplus volumio[3266]: ---------------------------- MPD announces system playlist update
Dec 29 15:55:35 rivoplus volumio[3266]: info: Ignoring MPD Status Update
Dec 29 15:55:35 rivoplus volumio[3266]: info:
Dec 29 15:55:35 rivoplus volumio[3266]: ---------------------------- MPD announces system playlist update
Dec 29 15:55:35 rivoplus volumio[3266]: info: Ignoring MPD Status Update
Dec 29 15:55:35 rivoplus volumio[3266]: error: updateQueue error: null
Dec 29 15:55:35 rivoplus volumio[3266]: info:
Dec 29 15:55:35 rivoplus volumio[3266]: ---------------------------- MPD announces system playlist update
Dec 29 15:55:35 rivoplus volumio[3266]: info: Ignoring MPD Status Update
Dec 29 15:55:35 rivoplus volumio[3266]: info: ------------------------------ 5ms
Dec 29 15:55:35 rivoplus volumio[3266]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/240475&soundQuality=HI_RES" took 5 milliseconds
Dec 29 15:55:35 rivoplus volumio[3266]: info: ------------------------------ 4ms
Dec 29 15:55:35 rivoplus volumio[3266]: info: ------------------------------ 3ms
Dec 29 15:55:35 rivoplus volumio[3266]: info: CoreStateMachine::setConsumeUpdateService mpd
Dec 29 15:55:35 rivoplus volumio[3266]: verbose: ControllerMpd::sendMpdCommand play
Dec 29 15:55:35 rivoplus volumio[3266]: info:
Dec 29 15:55:35 rivoplus volumio[3266]: ---------------------------- MPD announces system playlist update
Dec 29 15:55:35 rivoplus volumio[3266]: info: Ignoring MPD Status Update
Dec 29 15:55:35 rivoplus volumio[3266]: info:
Dec 29 15:55:35 rivoplus volumio[3266]: ---------------------------- MPD announces system playlist update
Dec 29 15:55:35 rivoplus volumio[3266]: info: Ignoring MPD Status Update
Dec 29 15:55:35 rivoplus volumio[3266]: STREAMING PROXY: Handling url /?data=tidal://song/240475&soundQuality=HI_RES
Dec 29 15:55:35 rivoplus volumio[3266]: info: ------------------------------ 7ms
Dec 29 15:55:35 rivoplus volumio[3266]: info: sendMpdCommand play took 5 milliseconds
Dec 29 15:55:35 rivoplus volumio[3266]: info: ------------------------------ 5ms
Dec 29 15:55:35 rivoplus volumio[3266]: info: ------------------------------ 3ms
Dec 29 15:55:35 rivoplus volumio[3266]: info: Executing endpoint getStreamUrltidal
Dec 29 15:55:35 rivoplus volumio[3266]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI
Dec 29 15:55:35 rivoplus volumio[3266]: info: getStreamUrl took 244 milliseconds
Dec 29 15:55:35 rivoplus volumio[3266]: STREAMING PROXY: Got real url: http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQ5NDMzNzcyMWM0NzUwZjUxZTQ2OTA2ZTJlNDI5NGE2Zi5tcDQ/0.flac?token=1767023728~MDg1ZTFiMjM5YTVjMWRkY2JmZTE2YmE0NzkxNzM1N2U5OGQ0ZDU0MA==
Dec 29 15:55:35 rivoplus volumio[3266]: STREAMING PROXY: Response: 200, length: 38394812
Dec 29 15:55:35 rivoplus volumio[3266]: info:
Dec 29 15:55:35 rivoplus volumio[3266]: ---------------------------- MPD announces state update: player
Dec 29 15:55:35 rivoplus volumio[3266]: info: ControllerMpd::getState
Dec 29 15:55:35 rivoplus volumio[3266]: verbose: ControllerMpd::sendMpdCommand status
Dec 29 15:55:35 rivoplus volumio[3266]: info:
Dec 29 15:55:35 rivoplus volumio[3266]: ---------------------------- MPD announces state update: player
Dec 29 15:55:35 rivoplus volumio[3266]: info: ControllerMpd::getState
Dec 29 15:55:35 rivoplus volumio[3266]: verbose: ControllerMpd::sendMpdCommand status
Dec 29 15:55:35 rivoplus volumio[3266]: info:
Dec 29 15:55:35 rivoplus volumio[3266]: ---------------------------- MPD announces state update: player
Dec 29 15:55:35 rivoplus volumio[3266]: info: ControllerMpd::getState
Dec 29 15:55:35 rivoplus volumio[3266]: verbose: ControllerMpd::sendMpdCommand status
Dec 29 15:55:35 rivoplus volumio[3266]: info: sendMpdCommand status took 34 milliseconds
Dec 29 15:55:35 rivoplus volumio[3266]: verbose: ControllerMpd::parseState
Dec 29 15:55:35 rivoplus volumio[3266]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 29 15:55:35 rivoplus volumio[3266]: info:
Dec 29 15:55:35 rivoplus volumio[3266]: ---------------------------- MPD announces state update: player
Dec 29 15:55:35 rivoplus volumio[3266]: info: ControllerMpd::getState
Dec 29 15:55:35 rivoplus volumio[3266]: verbose: ControllerMpd::sendMpdCommand status
Dec 29 15:55:35 rivoplus volumio[3266]: info: sendMpdCommand status took 34 milliseconds
Dec 29 15:55:35 rivoplus volumio[3266]: info: sendMpdCommand playlistinfo took 5 milliseconds
Dec 29 15:55:35 rivoplus volumio[3266]: verbose: ControllerMpd::parseState
Dec 29 15:55:35 rivoplus volumio[3266]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 29 15:55:35 rivoplus volumio[3266]: verbose: ControllerMpd::parseState
Dec 29 15:55:35 rivoplus volumio[3266]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 29 15:55:35 rivoplus volumio[3266]: verbose: ControllerMpd::parseTrackInfo
Dec 29 15:55:35 rivoplus volumio[3266]: info: ControllerMpd::pushState
Dec 29 15:55:35 rivoplus volumio[3266]: info: CoreCommandRouter::servicePushState
Dec 29 15:55:35 rivoplus volumio[3266]: info: CorePlayQueue::getTrack 51
Dec 29 15:55:35 rivoplus volumio[3266]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":347,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"240475&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/240475&soundQuality=HI_RES","trackType":"tidal"}
Dec 29 15:55:35 rivoplus volumio[3266]: verbose: CURRENT POSITION 51
Dec 29 15:55:35 rivoplus volumio[3266]: info: CoreStateMachine::syncState stateService play
Dec 29 15:55:35 rivoplus volumio[3266]: info: CoreStateMachine::syncState currentStatus stop
Dec 29 15:55:35 rivoplus volumio[3266]: info: ------------------------------ 50ms
Dec 29 15:55:35 rivoplus volumio[3266]: info:
Dec 29 15:55:35 rivoplus volumio[3266]: ---------------------------- MPD announces state update: player
Dec 29 15:55:35 rivoplus volumio[3266]: info: ControllerMpd::getState
Dec 29 15:55:35 rivoplus volumio[3266]: verbose: ControllerMpd::sendMpdCommand status
Dec 29 15:55:35 rivoplus volumio[3266]: info:
Dec 29 15:55:35 rivoplus volumio[3266]: ---------------------------- MPD announces state update: player
Dec 29 15:55:35 rivoplus volumio[3266]: info: ControllerMpd::getState
Dec 29 15:55:35 rivoplus volumio[3266]: verbose: ControllerMpd::sendMpdCommand status
Dec 29 15:55:35 rivoplus volumio[3266]: info: sendMpdCommand status took 20 milliseconds
Dec 29 15:55:35 rivoplus volumio[3266]: info: sendMpdCommand playlistinfo took 17 milliseconds
Dec 29 15:55:35 rivoplus volumio[3266]: info: sendMpdCommand playlistinfo took 17 milliseconds
Dec 29 15:55:35 rivoplus volumio[3266]: info: sendMpdCommand status took 9 milliseconds
Dec 29 15:55:35 rivoplus volumio[3266]: info: sendMpdCommand status took 7 milliseconds
Dec 29 15:55:35 rivoplus volumio[3266]: verbose: ControllerMpd::parseState
Dec 29 15:55:35 rivoplus volumio[3266]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 29 15:55:35 rivoplus volumio[3266]: verbose: ControllerMpd::parseTrackInfo
Dec 29 15:55:35 rivoplus volumio[3266]: verbose: ControllerMpd::parseTrackInfo
Dec 29 15:55:35 rivoplus volumio[3266]: verbose: ControllerMpd::parseState
Dec 29 15:55:35 rivoplus volumio[3266]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 29 15:55:35 rivoplus volumio[3266]: verbose: ControllerMpd::parseState
Dec 29 15:55:35 rivoplus volumio[3266]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 29 15:55:36 rivoplus volumio[3266]: info: ControllerMpd::pushState
Dec 29 15:55:36 rivoplus volumio[3266]: info: CoreCommandRouter::servicePushState
Dec 29 15:55:36 rivoplus volumio[3266]: info: CorePlayQueue::getTrack 51
Dec 29 15:55:36 rivoplus volumio[3266]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":347,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"240475&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/240475&soundQuality=HI_RES","trackType":"tidal"}
Dec 29 15:55:36 rivoplus volumio[3266]: verbose: CURRENT POSITION 51
Dec 29 15:55:36 rivoplus volumio[3266]: info: CoreStateMachine::syncState stateService play
Dec 29 15:55:36 rivoplus volumio[3266]: info: CoreStateMachine::syncState currentStatus play
Dec 29 15:55:36 rivoplus volumio[3266]: info: Received an update from plugin. extracting info from payload
Dec 29 15:55:36 rivoplus volumio[3266]: info: CoreStateMachine::pushState
Dec 29 15:55:36 rivoplus volumio[3266]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 29 15:55:36 rivoplus volumio[3266]: info: CoreCommandRouter::volumioPushState
Dec 29 15:55:36 rivoplus volumio[3266]: info: CoreStateMachine::pushState
Dec 29 15:55:36 rivoplus volumio[3266]: info: CoreCommandRouter::volumioPushState
Dec 29 15:55:36 rivoplus volumio[3266]: info: ControllerMpd::pushState
Dec 29 15:55:36 rivoplus volumio[3266]: info: CoreCommandRouter::servicePushState
Dec 29 15:55:36 rivoplus volumio[3266]: info: CorePlayQueue::getTrack 51
Dec 29 15:55:36 rivoplus volumio[3266]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":347,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"240475&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/240475&soundQuality=HI_RES","trackType":"tidal"}
Dec 29 15:55:36 rivoplus volumio[3266]: verbose: CURRENT POSITION 51
Dec 29 15:55:36 rivoplus volumio[3266]: info: CoreStateMachine::syncState stateService play
Dec 29 15:55:36 rivoplus volumio[3266]: info: CoreStateMachine::syncState currentStatus play
Dec 29 15:55:36 rivoplus volumio[3266]: info: Received an update from plugin. extracting info from payload
Dec 29 15:55:36 rivoplus volumio[3266]: info: CoreStateMachine::pushState
Dec 29 15:55:36 rivoplus volumio[3266]: info: CoreCommandRouter::volumioPushState
Dec 29 15:55:36 rivoplus volumio[3266]: info: CoreStateMachine::pushState
Dec 29 15:55:36 rivoplus volumio[3266]: info: CoreCommandRouter::volumioPushState
Dec 29 15:55:36 rivoplus volumio[3266]: info: ------------------------------ 164ms
Dec 29 15:55:36 rivoplus volumio[3266]: info: ------------------------------ 163ms
Dec 29 15:55:36 rivoplus volumio[3266]: info: sendMpdCommand playlistinfo took 108 milliseconds
Dec 29 15:55:36 rivoplus volumio[3266]: info: sendMpdCommand playlistinfo took 105 milliseconds
Dec 29 15:55:36 rivoplus volumio[3266]: info: sendMpdCommand playlistinfo took 105 milliseconds
Dec 29 15:55:36 rivoplus volumio[3266]: verbose: ControllerMpd::parseTrackInfo
Dec 29 15:55:36 rivoplus volumio[3266]: verbose: ControllerMpd::parseTrackInfo
Dec 29 15:55:36 rivoplus volumio[3266]: verbose: ControllerMpd::parseTrackInfo
Dec 29 15:55:36 rivoplus volumio[3266]: info: ControllerMpd::pushState
Dec 29 15:55:36 rivoplus volumio[3266]: info: CoreCommandRouter::servicePushState
Dec 29 15:55:36 rivoplus volumio[3266]: info: CorePlayQueue::getTrack 51
Dec 29 15:55:36 rivoplus volumio[3266]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":347,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"500 Kbps","isStreaming":false,"title":"240475&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/240475&soundQuality=HI_RES","trackType":"tidal"}
Dec 29 15:55:36 rivoplus volumio[3266]: verbose: CURRENT POSITION 51
Dec 29 15:55:36 rivoplus volumio[3266]: info: CoreStateMachine::syncState stateService play
Dec 29 15:55:36 rivoplus volumio[3266]: info: CoreStateMachine::syncState currentStatus play
Dec 29 15:55:36 rivoplus volumio[3266]: info: Received an update from plugin. extracting info from payload
Dec 29 15:55:36 rivoplus volumio[3266]: info: CoreStateMachine::pushState
Dec 29 15:55:36 rivoplus volumio[3266]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 29 15:55:36 rivoplus volumio[3266]: info: CoreCommandRouter::volumioPushState
Dec 29 15:55:36 rivoplus volumio[3266]: info: CoreStateMachine::pushState
Dec 29 15:55:36 rivoplus volumio[3266]: info: CoreCommandRouter::volumioPushState
Dec 29 15:55:36 rivoplus volumio[3266]: info: ControllerMpd::pushState
Dec 29 15:55:36 rivoplus volumio[3266]: info: CoreCommandRouter::servicePushState
Dec 29 15:55:36 rivoplus volumio[3266]: info: CorePlayQueue::getTrack 51
Dec 29 15:55:36 rivoplus volumio[3266]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":347,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"575 Kbps","isStreaming":false,"title":"240475&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/240475&soundQuality=HI_RES","trackType":"tidal"}
Dec 29 15:55:36 rivoplus volumio[3266]: verbose: CURRENT POSITION 51
Dec 29 15:55:36 rivoplus volumio[3266]: info: CoreStateMachine::syncState stateService play
Dec 29 15:55:36 rivoplus volumio[3266]: info: CoreStateMachine::syncState currentStatus play
Dec 29 15:55:36 rivoplus volumio[3266]: info: Received an update from plugin. extracting info from payload
Dec 29 15:55:36 rivoplus volumio[3266]: info: CoreStateMachine::pushState
Dec 29 15:55:36 rivoplus volumio[3266]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 29 15:55:36 rivoplus volumio[3266]: info: CoreCommandRouter::volumioPushState
Dec 29 15:55:36 rivoplus volumio[3266]: info: CoreStateMachine::pushState
Dec 29 15:55:36 rivoplus volumio[3266]: info: CoreCommandRouter::volumioPushState
Dec 29 15:55:36 rivoplus volumio[3266]: info: ControllerMpd::pushState
Dec 29 15:55:36 rivoplus volumio[3266]: info: CoreCommandRouter::servicePushState
Dec 29 15:55:36 rivoplus volumio[3266]: info: CorePlayQueue::getTrack 51
Dec 29 15:55:36 rivoplus volumio[3266]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":347,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"619 Kbps","isStreaming":false,"title":"240475&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/240475&soundQuality=HI_RES","trackType":"tidal"}
Dec 29 15:55:36 rivoplus volumio[3266]: verbose: CURRENT POSITION 51
Dec 29 15:55:36 rivoplus volumio[3266]: info: CoreStateMachine::syncState stateService play
Dec 29 15:55:36 rivoplus volumio[3266]: info: CoreStateMachine::syncState currentStatus play
Dec 29 15:55:36 rivoplus volumio[3266]: info: Received an update from plugin. extracting info from payload
Dec 29 15:55:36 rivoplus volumio[3266]: info: CoreStateMachine::pushState
Dec 29 15:55:36 rivoplus volumio[3266]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 29 15:55:36 rivoplus volumio[3266]: info: CoreCommandRouter::volumioPushState
Dec 29 15:55:36 rivoplus volumio[3266]: info: CoreStateMachine::pushState
Dec 29 15:55:36 rivoplus volumio[3266]: info: CoreCommandRouter::volumioPushState
Dec 29 15:55:36 rivoplus volumio[3266]: info: ------------------------------ 253ms
Dec 29 15:55:36 rivoplus volumio[3266]: info: ------------------------------ 242ms
Dec 29 15:55:36 rivoplus volumio[3266]: info: ------------------------------ 239ms
Dec 29 15:55:36 rivoplus volumio[3266]: info: Preload queue cleared
Dec 29 15:55:36 rivoplus volumio[3266]: info: CoreCommandRouter::volumioReplaceandPlayItems
Dec 29 15:55:36 rivoplus volumio[3266]: info: CoreStateMachine::ClearQueue
Dec 29 15:55:36 rivoplus volumio[3266]: info: CoreStateMachine::stop
Dec 29 15:55:36 rivoplus volumio[3266]: info: CoreStateMachine::setConsumeUpdateService undefined
Dec 29 15:55:36 rivoplus volumio[3266]: info: CoreStateMachine::stPlaybackTimer
Dec 29 15:55:36 rivoplus volumio[3266]: info: CoreStateMachine::updateTrackBlock
Dec 29 15:55:36 rivoplus volumio[3266]: info: CorePlayQueue::getTrackBlock
Dec 29 15:55:36 rivoplus volumio[3266]: info: CoreStateMachine::pushState
Dec 29 15:55:36 rivoplus volumio[3266]: info: CorePlayQueue::getTrack 51
Dec 29 15:55:36 rivoplus volumio[3266]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 29 15:55:36 rivoplus volumio[3266]: info: CoreCommandRouter::volumioPushState
Dec 29 15:55:36 rivoplus volumio[3266]: info: CoreStateMachine::serviceStop
Dec 29 15:55:36 rivoplus volumio[3266]: info: CorePlayQueue::getTrack 51
Dec 29 15:55:36 rivoplus volumio[3266]: info: CoreCommandRouter::serviceStop
Dec 29 15:55:36 rivoplus volumio[3266]: info: [1767020136838] ControllerTidal::stop
Dec 29 15:55:36 rivoplus volumio[3266]: info: CoreStateMachine::setConsumeUpdateService mpd
Dec 29 15:55:36 rivoplus volumio[3266]: info: ControllerMpd::stop
Dec 29 15:55:36 rivoplus volumio[3266]: verbose: ControllerMpd::sendMpdCommand stop
Dec 29 15:55:36 rivoplus volumio[3266]: info: CorePlayQueue::clearPlayQueue
Dec 29 15:55:36 rivoplus volumio[3266]: info: CorePlayQueue::saveQueue
Dec 29 15:55:36 rivoplus volumio[3266]: info: CoreCommandRouter::volumioPushQueue
Dec 29 15:55:36 rivoplus volumio[3266]: info: CoreStateMachine::addQueueItems
Dec 29 15:55:36 rivoplus volumio[3266]: info: CorePlayQueue::addQueueItems
Dec 29 15:55:36 rivoplus volumio[3266]: info: Preload queue cleared
Dec 29 15:55:36 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/16024087
Dec 29 15:55:36 rivoplus volumio[3266]: info: Using cached record of: tidal://song/16024087
Dec 29 15:55:36 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/99920827
Dec 29 15:55:36 rivoplus volumio[3266]: info: Using cached record of: tidal://song/99920827
Dec 29 15:55:36 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/68988294
Dec 29 15:55:36 rivoplus volumio[3266]: info: Using cached record of: tidal://song/68988294
Dec 29 15:55:36 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/4167367
Dec 29 15:55:36 rivoplus volumio[3266]: info: Using cached record of: tidal://song/4167367
Dec 29 15:55:36 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/396060895
Dec 29 15:55:36 rivoplus volumio[3266]: info: Using cached record of: tidal://song/396060895
Dec 29 15:55:36 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/396060905
Dec 29 15:55:36 rivoplus volumio[3266]: info: Using cached record of: tidal://song/396060905
Dec 29 15:55:36 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/396060897
Dec 29 15:55:36 rivoplus volumio[3266]: info: Using cached record of: tidal://song/396060897
Dec 29 15:55:36 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/396060900
Dec 29 15:55:36 rivoplus volumio[3266]: info: Using cached record of: tidal://song/396060900
Dec 29 15:55:36 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/266261642
Dec 29 15:55:36 rivoplus volumio[3266]: info: Using cached record of: tidal://song/266261642
Dec 29 15:55:36 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/96899507
Dec 29 15:55:36 rivoplus volumio[3266]: info: Using cached record of: tidal://song/96899507
Dec 29 15:55:36 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/123150061
Dec 29 15:55:36 rivoplus volumio[3266]: info: Using cached record of: tidal://song/123150061
Dec 29 15:55:36 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/182951917
Dec 29 15:55:36 rivoplus volumio[3266]: info: Exploding uri tidal://song/182951917 in service tidal
Dec 29 15:55:36 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/182951923
Dec 29 15:55:36 rivoplus volumio[3266]: info: Exploding uri tidal://song/182951923 in service tidal
Dec 29 15:55:36 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/175542203
Dec 29 15:55:36 rivoplus volumio[3266]: info: Using cached record of: tidal://song/175542203
Dec 29 15:55:36 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/23567300
Dec 29 15:55:36 rivoplus volumio[3266]: info: Using cached record of: tidal://song/23567300
Dec 29 15:55:36 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/51017424
Dec 29 15:55:36 rivoplus volumio[3266]: info: Using cached record of: tidal://song/51017424
Dec 29 15:55:36 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/51017425
Dec 29 15:55:36 rivoplus volumio[3266]: info: Using cached record of: tidal://song/51017425
Dec 29 15:55:36 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/111491556
Dec 29 15:55:36 rivoplus volumio[3266]: info: Exploding uri tidal://song/111491556 in service tidal
Dec 29 15:55:36 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/88393483
Dec 29 15:55:36 rivoplus volumio[3266]: info: Using cached record of: tidal://song/88393483
Dec 29 15:55:36 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/71882758
Dec 29 15:55:36 rivoplus volumio[3266]: info: Using cached record of: tidal://song/71882758
Dec 29 15:55:36 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/142841005
Dec 29 15:55:36 rivoplus volumio[3266]: info: Using cached record of: tidal://song/142841005
Dec 29 15:55:36 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/172902026
Dec 29 15:55:36 rivoplus volumio[3266]: info: Using cached record of: tidal://song/172902026
Dec 29 15:55:36 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/174184210
Dec 29 15:55:36 rivoplus volumio[3266]: info: Using cached record of: tidal://song/174184210
Dec 29 15:55:36 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/6803518
Dec 29 15:55:36 rivoplus volumio[3266]: info: Using cached record of: tidal://song/6803518
Dec 29 15:55:36 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/6803520
Dec 29 15:55:36 rivoplus volumio[3266]: info: Using cached record of: tidal://song/6803520
Dec 29 15:55:36 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/16024089
Dec 29 15:55:36 rivoplus volumio[3266]: info: Using cached record of: tidal://song/16024089
Dec 29 15:55:36 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/206092212
Dec 29 15:55:36 rivoplus volumio[3266]: info: Using cached record of: tidal://song/206092212
Dec 29 15:55:36 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/206092214
Dec 29 15:55:36 rivoplus volumio[3266]: info: Using cached record of: tidal://song/206092214
Dec 29 15:55:36 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/18057959
Dec 29 15:55:36 rivoplus volumio[3266]: info: Using cached record of: tidal://song/18057959
Dec 29 15:55:36 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/17715072
Dec 29 15:55:36 rivoplus volumio[3266]: info: Using cached record of: tidal://song/17715072
Dec 29 15:55:36 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/87252049
Dec 29 15:55:36 rivoplus volumio[3266]: info: Using cached record of: tidal://song/87252049
Dec 29 15:55:36 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/202266421
Dec 29 15:55:36 rivoplus volumio[3266]: info: Using cached record of: tidal://song/202266421
Dec 29 15:55:36 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/257767340
Dec 29 15:55:36 rivoplus volumio[3266]: info: Using cached record of: tidal://song/257767340
Dec 29 15:55:36 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/257767341
Dec 29 15:55:36 rivoplus volumio[3266]: info: Using cached record of: tidal://song/257767341
Dec 29 15:55:36 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/257767342
Dec 29 15:55:36 rivoplus volumio[3266]: info: Using cached record of: tidal://song/257767342
Dec 29 15:55:36 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/11344524
Dec 29 15:55:36 rivoplus volumio[3266]: info: Using cached record of: tidal://song/11344524
Dec 29 15:55:36 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/11344515
Dec 29 15:55:36 rivoplus volumio[3266]: info: Using cached record of: tidal://song/11344515
Dec 29 15:55:36 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/465528700
Dec 29 15:55:36 rivoplus volumio[3266]: info: Using cached record of: tidal://song/465528700
Dec 29 15:55:36 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/1184888
Dec 29 15:55:36 rivoplus volumio[3266]: info: Using cached record of: tidal://song/1184888
Dec 29 15:55:36 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/1184893
Dec 29 15:55:36 rivoplus volumio[3266]: info: Using cached record of: tidal://song/1184893
Dec 29 15:55:36 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/16024112
Dec 29 15:55:36 rivoplus volumio[3266]: info: Using cached record of: tidal://song/16024112
Dec 29 15:55:36 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/184779289
Dec 29 15:55:36 rivoplus volumio[3266]: info: Using cached record of: tidal://song/184779289
Dec 29 15:55:36 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/16024568
Dec 29 15:55:36 rivoplus volumio[3266]: info: Using cached record of: tidal://song/16024568
Dec 29 15:55:36 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/16026218
Dec 29 15:55:36 rivoplus volumio[3266]: info: Using cached record of: tidal://song/16026218
Dec 29 15:55:36 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/16025816
Dec 29 15:55:36 rivoplus volumio[3266]: info: Using cached record of: tidal://song/16025816
Dec 29 15:55:36 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/243640456
Dec 29 15:55:36 rivoplus volumio[3266]: info: Using cached record of: tidal://song/243640456
Dec 29 15:55:36 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/59673593
Dec 29 15:55:36 rivoplus volumio[3266]: info: Using cached record of: tidal://song/59673593
Dec 29 15:55:36 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/16024566
Dec 29 15:55:36 rivoplus volumio[3266]: info: Using cached record of: tidal://song/16024566
Dec 29 15:55:36 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/1477891
Dec 29 15:55:36 rivoplus volumio[3266]: info: Using cached record of: tidal://song/1477891
Dec 29 15:55:36 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/59962973
Dec 29 15:55:36 rivoplus volumio[3266]: info: Using cached record of: tidal://song/59962973
Dec 29 15:55:36 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/3872597
Dec 29 15:55:36 rivoplus volumio[3266]: info: Using cached record of: tidal://song/3872597
Dec 29 15:55:36 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/1477893
Dec 29 15:55:36 rivoplus volumio[3266]: info: Using cached record of: tidal://song/1477893
Dec 29 15:55:36 rivoplus volumio[3266]: STREAMING PROXY: Client dropped request, destroying
Dec 29 15:55:36 rivoplus wpa_supplicant[3179]: wlan0: WPA: Group rekeying completed with dc:15:c8:e7:e2:92 [GTK=CCMP]
Dec 29 15:55:36 rivoplus volumio[3266]: info:
Dec 29 15:55:36 rivoplus volumio[3266]: ---------------------------- MPD announces state update: player
Dec 29 15:55:36 rivoplus volumio[3266]: info: sendMpdCommand stop took 87 milliseconds
Dec 29 15:55:36 rivoplus volumio[3266]: info: ControllerMpd::getState
Dec 29 15:55:36 rivoplus volumio[3266]: verbose: ControllerMpd::sendMpdCommand status
Dec 29 15:55:36 rivoplus volumio[3266]: info: sendMpdCommand status took 1 milliseconds
Dec 29 15:55:36 rivoplus volumio[3266]: verbose: ControllerMpd::parseState
Dec 29 15:55:36 rivoplus volumio[3266]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 29 15:55:36 rivoplus volumio[3266]: info:
Dec 29 15:55:36 rivoplus volumio[3266]: ---------------------------- MPD announces state update: player
Dec 29 15:55:36 rivoplus volumio[3266]: info: ControllerMpd::getState
Dec 29 15:55:36 rivoplus volumio[3266]: verbose: ControllerMpd::sendMpdCommand status
Dec 29 15:55:36 rivoplus volumio[3266]: info:
Dec 29 15:55:36 rivoplus volumio[3266]: ---------------------------- MPD announces state update: player
Dec 29 15:55:36 rivoplus volumio[3266]: info: ControllerMpd::getState
Dec 29 15:55:36 rivoplus volumio[3266]: verbose: ControllerMpd::sendMpdCommand status
Dec 29 15:55:36 rivoplus volumio[3266]: info: sendMpdCommand playlistinfo took 4 milliseconds
Dec 29 15:55:36 rivoplus volumio[3266]: info: sendMpdCommand status took 2 milliseconds
Dec 29 15:55:36 rivoplus volumio[3266]: info: sendMpdCommand status took 2 milliseconds
Dec 29 15:55:36 rivoplus volumio[3266]: verbose: ControllerMpd::parseTrackInfo
Dec 29 15:55:36 rivoplus volumio[3266]: verbose: ControllerMpd::parseState
Dec 29 15:55:36 rivoplus volumio[3266]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 29 15:55:36 rivoplus volumio[3266]: verbose: ControllerMpd::parseState
Dec 29 15:55:36 rivoplus volumio[3266]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 29 15:55:36 rivoplus volumio[3266]: info: ControllerMpd::pushState
Dec 29 15:55:36 rivoplus volumio[3266]: info: CoreCommandRouter::servicePushState
Dec 29 15:55:36 rivoplus volumio[3266]: info: CoreStateMachine::pushState
Dec 29 15:55:36 rivoplus volumio[3266]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 29 15:55:36 rivoplus volumio[3266]: info: CoreCommandRouter::volumioPushState
Dec 29 15:55:36 rivoplus volumio[3266]: info: CorePlayQueue::getTrack 51
Dec 29 15:55:36 rivoplus volumio[3266]: 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":"240475&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/240475&soundQuality=HI_RES","trackType":"tidal"}
Dec 29 15:55:36 rivoplus volumio[3266]: verbose: CURRENT POSITION 51
Dec 29 15:55:36 rivoplus volumio[3266]: info: CoreStateMachine::syncState stateService stop
Dec 29 15:55:36 rivoplus volumio[3266]: info: CoreStateMachine::syncState currentStatus stop
Dec 29 15:55:36 rivoplus volumio[3266]: info: CoreStateMachine::pushState
Dec 29 15:55:36 rivoplus volumio[3266]: info: CoreCommandRouter::volumioPushState
Dec 29 15:55:36 rivoplus volumio[3266]: info: No code
Dec 29 15:55:36 rivoplus volumio[3266]: info: CoreStateMachine::pushState
Dec 29 15:55:36 rivoplus volumio[3266]: info: CoreCommandRouter::volumioPushState
Dec 29 15:55:36 rivoplus volumio[3266]: info: ------------------------------ 62ms
Dec 29 15:55:36 rivoplus volumio[3266]: info: sendMpdCommand playlistinfo took 55 milliseconds
Dec 29 15:55:36 rivoplus volumio[3266]: info: sendMpdCommand playlistinfo took 55 milliseconds
Dec 29 15:55:36 rivoplus volumio[3266]: verbose: ControllerMpd::parseTrackInfo
Dec 29 15:55:36 rivoplus volumio[3266]: verbose: ControllerMpd::parseTrackInfo
Dec 29 15:55:36 rivoplus volumio[3266]: info: ControllerMpd::pushState
Dec 29 15:55:36 rivoplus volumio[3266]: info: CoreCommandRouter::servicePushState
Dec 29 15:55:36 rivoplus volumio[3266]: info: CoreStateMachine::pushState
Dec 29 15:55:36 rivoplus volumio[3266]: info: CoreCommandRouter::volumioPushState
Dec 29 15:55:36 rivoplus volumio[3266]: info: CorePlayQueue::getTrack 51
Dec 29 15:55:36 rivoplus volumio[3266]: 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":"240475&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/240475&soundQuality=HI_RES","trackType":"tidal"}
Dec 29 15:55:36 rivoplus volumio[3266]: verbose: CURRENT POSITION 51
Dec 29 15:55:36 rivoplus volumio[3266]: info: CoreStateMachine::syncState stateService stop
Dec 29 15:55:36 rivoplus volumio[3266]: info: CoreStateMachine::syncState currentStatus stop
Dec 29 15:55:36 rivoplus volumio[3266]: info: CoreStateMachine::pushState
Dec 29 15:55:36 rivoplus volumio[3266]: info: CoreCommandRouter::volumioPushState
Dec 29 15:55:37 rivoplus volumio[3266]: info: No code
Dec 29 15:55:37 rivoplus volumio[3266]: info: CoreStateMachine::pushState
Dec 29 15:55:37 rivoplus volumio[3266]: info: CoreCommandRouter::volumioPushState
Dec 29 15:55:37 rivoplus volumio[3266]: info: ControllerMpd::pushState
Dec 29 15:55:37 rivoplus volumio[3266]: info: CoreCommandRouter::servicePushState
Dec 29 15:55:37 rivoplus volumio[3266]: info: CoreStateMachine::pushState
Dec 29 15:55:37 rivoplus volumio[3266]: info: CoreCommandRouter::volumioPushState
Dec 29 15:55:37 rivoplus volumio[3266]: info: CorePlayQueue::getTrack 51
Dec 29 15:55:37 rivoplus volumio[3266]: 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":"240475&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/240475&soundQuality=HI_RES","trackType":"tidal"}
Dec 29 15:55:37 rivoplus volumio[3266]: verbose: CURRENT POSITION 51
Dec 29 15:55:37 rivoplus volumio[3266]: info: CoreStateMachine::syncState stateService stop
Dec 29 15:55:37 rivoplus volumio[3266]: info: CoreStateMachine::syncState currentStatus stop
Dec 29 15:55:37 rivoplus volumio[3266]: info: CoreStateMachine::pushState
Dec 29 15:55:37 rivoplus volumio[3266]: info: CoreCommandRouter::volumioPushState
Dec 29 15:55:37 rivoplus volumio[3266]: info: No code
Dec 29 15:55:37 rivoplus volumio[3266]: info: CoreStateMachine::pushState
Dec 29 15:55:37 rivoplus volumio[3266]: info: CoreCommandRouter::volumioPushState
Dec 29 15:55:37 rivoplus volumio[3266]: info: ------------------------------ 138ms
Dec 29 15:55:37 rivoplus volumio[3266]: info: ------------------------------ 138ms
Dec 29 15:55:37 rivoplus volumio[3266]: info: Signalling Playback active due to playback status change
Dec 29 15:55:37 rivoplus volumio[3266]: info: MCU Signalled Playback Inactive
Dec 29 15:55:37 rivoplus volumio[3266]: info: MCU Signalled Playback Active
Dec 29 15:55:37 rivoplus volumio[3266]: info: explodeTIDALUri took 397 milliseconds
Dec 29 15:55:37 rivoplus volumio[3266]: error: TIDAL Browse Error: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [182951917] not found"}
Dec 29 15:55:37 rivoplus volumio[3266]: error: Commandrouter: Cannot explode uri tidal://song/182951917 from service tidal: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [182951917] not found"}
Dec 29 15:55:37 rivoplus volumio[3266]: info: explodeTIDALUri took 419 milliseconds
Dec 29 15:55:37 rivoplus volumio[3266]: error: TIDAL Browse Error: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [182951923] not found"}
Dec 29 15:55:37 rivoplus volumio[3266]: error: Commandrouter: Cannot explode uri tidal://song/182951923 from service tidal: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [182951923] not found"}
Dec 29 15:55:37 rivoplus volumio[3266]: info: explodeTIDALUri took 442 milliseconds
Dec 29 15:55:37 rivoplus volumio[3266]: error: TIDAL Browse Error: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [111491556] not found"}
Dec 29 15:55:37 rivoplus volumio[3266]: error: Commandrouter: Cannot explode uri tidal://song/111491556 from service tidal: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [111491556] not found"}
Dec 29 15:55:37 rivoplus volumio[3266]: info: CoreCommandRouter::volumioPushQueue
Dec 29 15:55:37 rivoplus volumio[3266]: info: CorePlayQueue::saveQueue
Dec 29 15:55:37 rivoplus volumio[3266]: info: CoreStateMachine::updateTrackBlock
Dec 29 15:55:37 rivoplus volumio[3266]: info: CorePlayQueue::getTrackBlock
Dec 29 15:55:37 rivoplus volumio[3266]: info: CoreCommandRouter::volumioPlay
Dec 29 15:55:37 rivoplus volumio[3266]: info: CoreStateMachine::play index 51
Dec 29 15:55:37 rivoplus volumio[3266]: info: CoreStateMachine::setConsumeUpdateService undefined
Dec 29 15:55:37 rivoplus volumio[3266]: info: CoreStateMachine::addQueueItems
Dec 29 15:55:37 rivoplus volumio[3266]: info: CorePlayQueue::addQueueItems
Dec 29 15:55:37 rivoplus volumio[3266]: info: Preload queue cleared
Dec 29 15:55:37 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/341263271
Dec 29 15:55:37 rivoplus volumio[3266]: info: Using cached record of: tidal://song/341263271
Dec 29 15:55:37 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/25652
Dec 29 15:55:37 rivoplus volumio[3266]: info: Using cached record of: tidal://song/25652
Dec 29 15:55:37 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/240475
Dec 29 15:55:37 rivoplus volumio[3266]: info: Using cached record of: tidal://song/240475
Dec 29 15:55:37 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/205781747
Dec 29 15:55:37 rivoplus volumio[3266]: info: Using cached record of: tidal://song/205781747
Dec 29 15:55:37 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/132779700
Dec 29 15:55:37 rivoplus volumio[3266]: info: Using cached record of: tidal://song/132779700
Dec 29 15:55:37 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/16023976
Dec 29 15:55:37 rivoplus volumio[3266]: info: Using cached record of: tidal://song/16023976
Dec 29 15:55:37 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/233502187
Dec 29 15:55:37 rivoplus volumio[3266]: info: Using cached record of: tidal://song/233502187
Dec 29 15:55:37 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/5170460
Dec 29 15:55:37 rivoplus volumio[3266]: info: Using cached record of: tidal://song/5170460
Dec 29 15:55:37 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/125077738
Dec 29 15:55:37 rivoplus volumio[3266]: info: Using cached record of: tidal://song/125077738
Dec 29 15:55:37 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/23941814
Dec 29 15:55:37 rivoplus volumio[3266]: info: Using cached record of: tidal://song/23941814
Dec 29 15:55:37 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/285337586
Dec 29 15:55:37 rivoplus volumio[3266]: info: Using cached record of: tidal://song/285337586
Dec 29 15:55:37 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/291701511
Dec 29 15:55:37 rivoplus volumio[3266]: info: Using cached record of: tidal://song/291701511
Dec 29 15:55:37 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/125079037
Dec 29 15:55:37 rivoplus volumio[3266]: info: Using cached record of: tidal://song/125079037
Dec 29 15:55:37 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/124739692
Dec 29 15:55:37 rivoplus volumio[3266]: info: Using cached record of: tidal://song/124739692
Dec 29 15:55:37 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/16023740
Dec 29 15:55:37 rivoplus volumio[3266]: info: Using cached record of: tidal://song/16023740
Dec 29 15:55:37 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/16023741
Dec 29 15:55:37 rivoplus volumio[3266]: info: Using cached record of: tidal://song/16023741
Dec 29 15:55:37 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/20589793
Dec 29 15:55:37 rivoplus volumio[3266]: info: Using cached record of: tidal://song/20589793
Dec 29 15:55:37 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/20589797
Dec 29 15:55:37 rivoplus volumio[3266]: info: Using cached record of: tidal://song/20589797
Dec 29 15:55:37 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/20589806
Dec 29 15:55:37 rivoplus volumio[3266]: info: Using cached record of: tidal://song/20589806
Dec 29 15:55:37 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/299119014
Dec 29 15:55:37 rivoplus volumio[3266]: info: Using cached record of: tidal://song/299119014
Dec 29 15:55:37 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/25824862
Dec 29 15:55:37 rivoplus volumio[3266]: info: Using cached record of: tidal://song/25824862
Dec 29 15:55:37 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/366453392
Dec 29 15:55:37 rivoplus volumio[3266]: info: Using cached record of: tidal://song/366453392
Dec 29 15:55:37 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/431923387
Dec 29 15:55:37 rivoplus volumio[3266]: info: Using cached record of: tidal://song/431923387
Dec 29 15:55:37 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/324730312
Dec 29 15:55:37 rivoplus volumio[3266]: info: Using cached record of: tidal://song/324730312
Dec 29 15:55:37 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/446085187
Dec 29 15:55:37 rivoplus volumio[3266]: info: Using cached record of: tidal://song/446085187
Dec 29 15:55:37 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/88632568
Dec 29 15:55:37 rivoplus volumio[3266]: info: Using cached record of: tidal://song/88632568
Dec 29 15:55:37 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/180557746
Dec 29 15:55:37 rivoplus volumio[3266]: info: Using cached record of: tidal://song/180557746
Dec 29 15:55:37 rivoplus volumio[3266]: info: Adding Item to queue: tidal://song/180557740
Dec 29 15:55:37 rivoplus volumio[3266]: info: Using cached record of: tidal://song/180557740
Dec 29 15:55:37 rivoplus volumio[3266]: info: CoreStateMachine::stop
Dec 29 15:55:37 rivoplus volumio[3266]: info: CoreStateMachine::setConsumeUpdateService undefined
Dec 29 15:55:37 rivoplus volumio[3266]: info: CoreCommandRouter::volumioPushQueue
Dec 29 15:55:37 rivoplus volumio[3266]: info: CorePlayQueue::saveQueue
Dec 29 15:55:37 rivoplus volumio[3266]: info: CoreStateMachine::play index undefined
Dec 29 15:55:37 rivoplus volumio[3266]: info: CoreStateMachine::setConsumeUpdateService undefined
Dec 29 15:55:37 rivoplus volumio[3266]: info: CoreStateMachine::updateTrackBlock
Dec 29 15:55:37 rivoplus volumio[3266]: info: CorePlayQueue::getTrackBlock
Dec 29 15:55:37 rivoplus volumio[3266]: info: CorePlayQueue::getTrack 51
Dec 29 15:55:37 rivoplus volumio[3266]: info: CoreStateMachine::startPlaybackTimer
Dec 29 15:55:37 rivoplus volumio[3266]: info: CorePlayQueue::getTrack 51
Dec 29 15:55:37 rivoplus volumio[3266]: info: [1767020137378] ControllerTidal::clearAddPlayTrack
Dec 29 15:55:37 rivoplus volumio[3266]: info: Getting stream with soundQuality HI_RES
Dec 29 15:55:37 rivoplus volumio[3266]: verbose: ControllerMpd::sendMpdCommand stop
Dec 29 15:55:37 rivoplus volumio[3266]: info: sendMpdCommand stop took 20 milliseconds
Dec 29 15:55:37 rivoplus volumio[3266]: verbose: ControllerMpd::sendMpdCommand clear
Dec 29 15:55:37 rivoplus volumio[3266]: info:
Dec 29 15:55:37 rivoplus volumio[3266]: ---------------------------- MPD announces system playlist update
Dec 29 15:55:37 rivoplus volumio[3266]: info: Ignoring MPD Status Update
Dec 29 15:55:37 rivoplus volumio[3266]: info: sendMpdCommand clear took 4 milliseconds
Dec 29 15:55:37 rivoplus volumio[3266]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/240475&soundQuality=HI_RES"
Dec 29 15:55:37 rivoplus volumio[3266]: info:
Dec 29 15:55:37 rivoplus volumio[3266]: ---------------------------- MPD announces system playlist update
Dec 29 15:55:37 rivoplus volumio[3266]: info: Ignoring MPD Status Update
Dec 29 15:55:37 rivoplus volumio[3266]: info:
Dec 29 15:55:37 rivoplus volumio[3266]: ---------------------------- MPD announces system playlist update
Dec 29 15:55:37 rivoplus volumio[3266]: info: Ignoring MPD Status Update
Dec 29 15:55:37 rivoplus volumio[3266]: error: updateQueue error: null
Dec 29 15:55:37 rivoplus volumio[3266]: info:
Dec 29 15:55:37 rivoplus volumio[3266]: ---------------------------- MPD announces system playlist update
Dec 29 15:55:37 rivoplus volumio[3266]: info: Ignoring MPD Status Update
Dec 29 15:55:37 rivoplus volumio[3266]: info: ------------------------------ 5ms
Dec 29 15:55:37 rivoplus volumio[3266]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/240475&soundQuality=HI_RES" took 4 milliseconds
Dec 29 15:55:37 rivoplus volumio[3266]: info: ------------------------------ 5ms
Dec 29 15:55:37 rivoplus volumio[3266]: info: ------------------------------ 3ms
Dec 29 15:55:37 rivoplus volumio[3266]: info: CoreStateMachine::setConsumeUpdateService mpd
Dec 29 15:55:37 rivoplus volumio[3266]: verbose: ControllerMpd::sendMpdCommand play
Dec 29 15:55:37 rivoplus volumio[3266]: info:
Dec 29 15:55:37 rivoplus volumio[3266]: ---------------------------- MPD announces system playlist update
Dec 29 15:55:37 rivoplus volumio[3266]: info: Ignoring MPD Status Update
Dec 29 15:55:37 rivoplus volumio[3266]: info:
Dec 29 15:55:37 rivoplus volumio[3266]: ---------------------------- MPD announces system playlist update
Dec 29 15:55:37 rivoplus volumio[3266]: info: Ignoring MPD Status Update
Dec 29 15:55:37 rivoplus volumio[3266]: STREAMING PROXY: Handling url /?data=tidal://song/240475&soundQuality=HI_RES
Dec 29 15:55:37 rivoplus volumio[3266]: info: ------------------------------ 7ms
Dec 29 15:55:37 rivoplus volumio[3266]: info: sendMpdCommand play took 5 milliseconds
Dec 29 15:55:37 rivoplus volumio[3266]: info: ------------------------------ 5ms
Dec 29 15:55:37 rivoplus volumio[3266]: info: ------------------------------ 3ms
Dec 29 15:55:37 rivoplus volumio[3266]: info: Executing endpoint getStreamUrltidal
Dec 29 15:55:37 rivoplus volumio[3266]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI
Dec 29 15:55:37 rivoplus volumio[3266]: info: getStreamUrl took 285 milliseconds
Dec 29 15:55:37 rivoplus volumio[3266]: STREAMING PROXY: Got real url: http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQ5NDMzNzcyMWM0NzUwZjUxZTQ2OTA2ZTJlNDI5NGE2Zi5tcDQ/0.flac?token=1767023728~MDg1ZTFiMjM5YTVjMWRkY2JmZTE2YmE0NzkxNzM1N2U5OGQ0ZDU0MA==
Dec 29 15:55:37 rivoplus volumio[3266]: STREAMING PROXY: Response: 200, length: 38394812
Dec 29 15:55:37 rivoplus volumio[3266]: info:
Dec 29 15:55:37 rivoplus volumio[3266]: ---------------------------- MPD announces state update: player
Dec 29 15:55:37 rivoplus volumio[3266]: info: ControllerMpd::getState
Dec 29 15:55:37 rivoplus volumio[3266]: verbose: ControllerMpd::sendMpdCommand status
Dec 29 15:55:37 rivoplus volumio[3266]: info:
Dec 29 15:55:37 rivoplus volumio[3266]: ---------------------------- MPD announces state update: player
Dec 29 15:55:37 rivoplus volumio[3266]: info: ControllerMpd::getState
Dec 29 15:55:37 rivoplus volumio[3266]: verbose: ControllerMpd::sendMpdCommand status
Dec 29 15:55:37 rivoplus volumio[3266]: info:
Dec 29 15:55:37 rivoplus volumio[3266]: ---------------------------- MPD announces state update: player
Dec 29 15:55:37 rivoplus volumio[3266]: info: ControllerMpd::getState
Dec 29 15:55:37 rivoplus volumio[3266]: verbose: ControllerMpd::sendMpdCommand status
Dec 29 15:55:37 rivoplus volumio[3266]: info:
Dec 29 15:55:37 rivoplus volumio[3266]: ---------------------------- MPD announces state update: player
Dec 29 15:55:37 rivoplus volumio[3266]: info: sendMpdCommand status took 22 milliseconds
Dec 29 15:55:37 rivoplus volumio[3266]: info: ControllerMpd::getState
Dec 29 15:55:37 rivoplus volumio[3266]: verbose: ControllerMpd::sendMpdCommand status
Dec 29 15:55:37 rivoplus volumio[3266]: verbose: ControllerMpd::parseState
Dec 29 15:55:37 rivoplus volumio[3266]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 29 15:55:37 rivoplus volumio[3266]: info: sendMpdCommand status took 35 milliseconds
Dec 29 15:55:37 rivoplus volumio[3266]: info: sendMpdCommand status took 35 milliseconds
Dec 29 15:55:37 rivoplus volumio[3266]: info: sendMpdCommand status took 14 milliseconds
Dec 29 15:55:37 rivoplus volumio[3266]: info: sendMpdCommand playlistinfo took 14 milliseconds
Dec 29 15:55:37 rivoplus volumio[3266]: verbose: ControllerMpd::parseState
Dec 29 15:55:37 rivoplus volumio[3266]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 29 15:55:37 rivoplus volumio[3266]: verbose: ControllerMpd::parseState
Dec 29 15:55:37 rivoplus volumio[3266]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 29 15:55:37 rivoplus volumio[3266]: verbose: ControllerMpd::parseState
Dec 29 15:55:37 rivoplus volumio[3266]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 29 15:55:37 rivoplus volumio[3266]: verbose: ControllerMpd::parseTrackInfo
Dec 29 15:55:37 rivoplus volumio[3266]: info: ControllerMpd::pushState
Dec 29 15:55:37 rivoplus volumio[3266]: info: CoreCommandRouter::servicePushState
Dec 29 15:55:37 rivoplus volumio[3266]: info: CorePlayQueue::getTrack 51
Dec 29 15:55:37 rivoplus volumio[3266]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":347,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"240475&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/240475&soundQuality=HI_RES","trackType":"tidal"}
Dec 29 15:55:37 rivoplus volumio[3266]: verbose: CURRENT POSITION 51
Dec 29 15:55:37 rivoplus volumio[3266]: info: CoreStateMachine::syncState stateService play
Dec 29 15:55:37 rivoplus volumio[3266]: info: CoreStateMachine::syncState currentStatus stop
Dec 29 15:55:37 rivoplus volumio[3266]: info: ------------------------------ 48ms
Dec 29 15:55:37 rivoplus volumio[3266]: info:
Dec 29 15:55:37 rivoplus volumio[3266]: ---------------------------- MPD announces state update: player
Dec 29 15:55:37 rivoplus volumio[3266]: info: ControllerMpd::getState
Dec 29 15:55:37 rivoplus volumio[3266]: verbose: ControllerMpd::sendMpdCommand status
Dec 29 15:55:37 rivoplus volumio[3266]: info:
Dec 29 15:55:37 rivoplus volumio[3266]: ---------------------------- MPD announces state update: player
Dec 29 15:55:37 rivoplus volumio[3266]: info: ControllerMpd::getState
Dec 29 15:55:37 rivoplus volumio[3266]: verbose: ControllerMpd::sendMpdCommand status
Dec 29 15:55:37 rivoplus volumio[3266]: info: sendMpdCommand playlistinfo took 15 milliseconds
Dec 29 15:55:37 rivoplus volumio[3266]: info: sendMpdCommand playlistinfo took 14 milliseconds
Dec 29 15:55:37 rivoplus volumio[3266]: info: sendMpdCommand playlistinfo took 12 milliseconds
Dec 29 15:55:37 rivoplus volumio[3266]: info: sendMpdCommand status took 5 milliseconds
Dec 29 15:55:37 rivoplus volumio[3266]: verbose: ControllerMpd::parseTrackInfo
Dec 29 15:55:37 rivoplus volumio[3266]: verbose: ControllerMpd::parseTrackInfo
Dec 29 15:55:37 rivoplus volumio[3266]: verbose: ControllerMpd::parseTrackInfo
Dec 29 15:55:37 rivoplus volumio[3266]: verbose: ControllerMpd::parseState
Dec 29 15:55:37 rivoplus volumio[3266]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 29 15:55:37 rivoplus volumio[3266]: info: ControllerMpd::pushState
Dec 29 15:55:37 rivoplus volumio[3266]: info: CoreCommandRouter::servicePushState
Dec 29 15:55:37 rivoplus volumio[3266]: info: CorePlayQueue::getTrack 51
Dec 29 15:55:37 rivoplus volumio[3266]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":347,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"240475&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/240475&soundQuality=HI_RES","trackType":"tidal"}
Dec 29 15:55:37 rivoplus volumio[3266]: verbose: CURRENT POSITION 51
Dec 29 15:55:37 rivoplus volumio[3266]: info: CoreStateMachine::syncState stateService play
Dec 29 15:55:37 rivoplus volumio[3266]: info: CoreStateMachine::syncState currentStatus play
Dec 29 15:55:37 rivoplus volumio[3266]: info: Received an update from plugin. extracting info from payload
Dec 29 15:55:37 rivoplus volumio[3266]: info: CoreStateMachine::pushState
Dec 29 15:55:37 rivoplus volumio[3266]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 29 15:55:37 rivoplus volumio[3266]: info: CoreCommandRouter::volumioPushState
Dec 29 15:55:37 rivoplus volumio[3266]: info: CoreStateMachine::pushState
Dec 29 15:55:37 rivoplus volumio[3266]: info: CoreCommandRouter::volumioPushState
Dec 29 15:55:37 rivoplus volumio[3266]: info: ControllerMpd::pushState
Dec 29 15:55:37 rivoplus volumio[3266]: info: CoreCommandRouter::servicePushState
Dec 29 15:55:37 rivoplus volumio[3266]: info: CorePlayQueue::getTrack 51
Dec 29 15:55:37 rivoplus volumio[3266]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":347,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"240475&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/240475&soundQuality=HI_RES","trackType":"tidal"}
Dec 29 15:55:37 rivoplus volumio[3266]: verbose: CURRENT POSITION 51
Dec 29 15:55:37 rivoplus volumio[3266]: info: CoreStateMachine::syncState stateService play
Dec 29 15:55:37 rivoplus volumio[3266]: info: CoreStateMachine::syncState currentStatus play
Dec 29 15:55:37 rivoplus volumio[3266]: info: Received an update from plugin. extracting info from payload
Dec 29 15:55:37 rivoplus volumio[3266]: info: CoreStateMachine::pushState
Dec 29 15:55:37 rivoplus volumio[3266]: info: CoreCommandRouter::volumioPushState
Dec 29 15:55:37 rivoplus volumio[3266]: info: CoreStateMachine::pushState
Dec 29 15:55:37 rivoplus volumio[3266]: info: CoreCommandRouter::volumioPushState
Dec 29 15:55:37 rivoplus volumio[3266]: info: ControllerMpd::pushState
Dec 29 15:55:37 rivoplus volumio[3266]: info: CoreCommandRouter::servicePushState
Dec 29 15:55:37 rivoplus volumio[3266]: info: CorePlayQueue::getTrack 51
Dec 29 15:55:37 rivoplus volumio[3266]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":347,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"240475&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/240475&soundQuality=HI_RES","trackType":"tidal"}
Dec 29 15:55:37 rivoplus volumio[3266]: verbose: CURRENT POSITION 51
Dec 29 15:55:37 rivoplus volumio[3266]: info: CoreStateMachine::syncState stateService play
Dec 29 15:55:37 rivoplus volumio[3266]: info: CoreStateMachine::syncState currentStatus play
Dec 29 15:55:37 rivoplus volumio[3266]: info: Received an update from plugin. extracting info from payload
Dec 29 15:55:37 rivoplus volumio[3266]: info: CoreStateMachine::pushState
Dec 29 15:55:37 rivoplus volumio[3266]: info: CoreCommandRouter::volumioPushState
Dec 29 15:55:37 rivoplus volumio[3266]: info: CoreStateMachine::pushState
Dec 29 15:55:37 rivoplus volumio[3266]: info: CoreCommandRouter::volumioPushState
Dec 29 15:55:37 rivoplus volumio[3266]: info: ------------------------------ 117ms
Dec 29 15:55:37 rivoplus volumio[3266]: info: ------------------------------ 117ms
Dec 29 15:55:37 rivoplus volumio[3266]: info: ------------------------------ 98ms
Dec 29 15:55:37 rivoplus volumio[3266]: info: sendMpdCommand status took 71 milliseconds
Dec 29 15:55:37 rivoplus volumio[3266]: info: sendMpdCommand playlistinfo took 66 milliseconds
Dec 29 15:55:37 rivoplus volumio[3266]: verbose: ControllerMpd::parseState
Dec 29 15:55:37 rivoplus volumio[3266]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 29 15:55:37 rivoplus volumio[3266]: verbose: ControllerMpd::parseTrackInfo
Dec 29 15:55:37 rivoplus volumio[3266]: info: ControllerMpd::pushState
Dec 29 15:55:37 rivoplus volumio[3266]: info: CoreCommandRouter::servicePushState
Dec 29 15:55:37 rivoplus volumio[3266]: info: CorePlayQueue::getTrack 51
Dec 29 15:55:37 rivoplus volumio[3266]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":347,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"575 Kbps","isStreaming":false,"title":"240475&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/240475&soundQuality=HI_RES","trackType":"tidal"}
Dec 29 15:55:37 rivoplus volumio[3266]: verbose: CURRENT POSITION 51
Dec 29 15:55:37 rivoplus volumio[3266]: info: CoreStateMachine::syncState stateService play
Dec 29 15:55:37 rivoplus volumio[3266]: info: CoreStateMachine::syncState currentStatus play
Dec 29 15:55:37 rivoplus volumio[3266]: info: Received an update from plugin. extracting info from payload
Dec 29 15:55:37 rivoplus volumio[3266]: info: CoreStateMachine::pushState
Dec 29 15:55:37 rivoplus volumio[3266]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 29 15:55:37 rivoplus volumio[3266]: info: CoreCommandRouter::volumioPushState
Dec 29 15:55:37 rivoplus volumio[3266]: info: CoreStateMachine::pushState
Dec 29 15:55:37 rivoplus volumio[3266]: info: CoreCommandRouter::volumioPushState
Dec 29 15:55:37 rivoplus volumio[3266]: info: ------------------------------ 93ms
Dec 29 15:55:37 rivoplus volumio[3266]: info: sendMpdCommand playlistinfo took 41 milliseconds
Dec 29 15:55:37 rivoplus volumio[3266]: verbose: ControllerMpd::parseTrackInfo
Dec 29 15:55:37 rivoplus volumio[3266]: info: ControllerMpd::pushState
Dec 29 15:55:37 rivoplus volumio[3266]: info: CoreCommandRouter::servicePushState
Dec 29 15:55:37 rivoplus volumio[3266]: info: CorePlayQueue::getTrack 51
Dec 29 15:55:37 rivoplus volumio[3266]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":347,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"605 Kbps","isStreaming":false,"title":"240475&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/240475&soundQuality=HI_RES","trackType":"tidal"}
Dec 29 15:55:37 rivoplus volumio[3266]: verbose: CURRENT POSITION 51
Dec 29 15:55:37 rivoplus volumio[3266]: info: CoreStateMachine::syncState stateService play
Dec 29 15:55:37 rivoplus volumio[3266]: info: CoreStateMachine::syncState currentStatus play
Dec 29 15:55:37 rivoplus volumio[3266]: info: Received an update from plugin. extracting info from payload
Dec 29 15:55:37 rivoplus volumio[3266]: info: CoreStateMachine::pushState
Dec 29 15:55:37 rivoplus volumio[3266]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 29 15:55:37 rivoplus volumio[3266]: info: CoreCommandRouter::volumioPushState
Dec 29 15:55:37 rivoplus volumio[3266]: info: CoreStateMachine::pushState
Dec 29 15:55:37 rivoplus volumio[3266]: info: CoreCommandRouter::volumioPushState
Dec 29 15:55:37 rivoplus volumio[3266]: info: ------------------------------ 127ms
Dec 29 15:55:41 rivoplus volumio[3266]: STREAMING PROXY: Client dropped request, destroying
Dec 29 15:55:52 rivoplus volumio[3266]: info: CoreCommandRouter::volumioGetState
Dec 29 15:55:55 rivoplus volumio[3266]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
Dec 29 15:55:55 rivoplus volumio[3266]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
Dec 29 15:55:56 rivoplus sudo[9215]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Dec 29 15:55:56 rivoplus sudo[9215]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 29 15:55:56 rivoplus sudo[9215]: pam_unix(sudo:session): session closed for user root
Dec 29 15:55:56 rivoplus sudo[9218]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Dec 29 15:55:56 rivoplus sudo[9218]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 29 15:55:56 rivoplus sudo[9218]: pam_unix(sudo:session): session closed for user root
Dec 29 15:55:56 rivoplus volumio[3266]: verbose: New Socket.io Connection to 192.168.178.45 from 192.168.178.21 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 7
Dec 29 15:55:56 rivoplus volumio[3266]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Dec 29 15:55:57 rivoplus volumio[3266]: info: CoreCommandRouter::volumioGetVisibleSources
Dec 29 15:55:57 rivoplus volumio[3266]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Dec 29 15:55:57 rivoplus volumio[3266]: info: CoreCommandRouter::volumioGetState
Dec 29 15:55:57 rivoplus volumio[3266]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Dec 29 15:55:57 rivoplus volumio[3266]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Dec 29 15:55:57 rivoplus volumio[3266]: info: Received Get System Info
Dec 29 15:55:57 rivoplus volumio[3266]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Dec 29 15:55:57 rivoplus volumio[3266]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Dec 29 15:55:57 rivoplus volumio[3266]: info: Discovery: Getting this device information
Dec 29 15:55:57 rivoplus volumio[3266]: info: CoreCommandRouter::volumioGetState
Dec 29 15:55:57 rivoplus volumio[3266]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Dec 29 15:55:57 rivoplus volumio[3266]: info: CoreCommandRouter::volumioGetState
Dec 29 15:55:57 rivoplus volumio[3266]: info: Listing playlists
Dec 29 15:55:57 rivoplus volumio[3266]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Dec 29 15:55:57 rivoplus volumio[3266]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Dec 29 15:55:57 rivoplus volumio[3266]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache
Dec 29 15:55:57 rivoplus sudo[9224]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Dec 29 15:55:57 rivoplus sudo[9224]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 29 15:55:57 rivoplus sudo[9224]: pam_unix(sudo:session): session closed for user root
Dec 29 15:55:57 rivoplus sudo[9226]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Dec 29 15:55:57 rivoplus sudo[9226]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 29 15:55:57 rivoplus sudo[9226]: pam_unix(sudo:session): session closed for user root
Dec 29 15:55:57 rivoplus volumio[3266]: verbose: New Socket.io Connection to 192.168.178.45 from 192.168.178.21 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 7
Dec 29 15:55:57 rivoplus volumio[3266]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Dec 29 15:55:57 rivoplus volumio[3266]: info: CoreCommandRouter::volumioGetVisibleSources
Dec 29 15:55:57 rivoplus volumio[3266]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Dec 29 15:55:57 rivoplus volumio[3266]: info: CoreCommandRouter::volumioGetState
Dec 29 15:55:57 rivoplus volumio[3266]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Dec 29 15:55:57 rivoplus volumio[3266]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Dec 29 15:55:57 rivoplus volumio[3266]: info: Received Get System Info
Dec 29 15:55:57 rivoplus volumio[3266]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Dec 29 15:55:57 rivoplus volumio[3266]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Dec 29 15:55:57 rivoplus volumio[3266]: info: Discovery: Getting this device information
Dec 29 15:55:57 rivoplus volumio[3266]: info: CoreCommandRouter::volumioGetState
Dec 29 15:55:57 rivoplus volumio[3266]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Dec 29 15:55:57 rivoplus volumio[3266]: info: CoreCommandRouter::volumioGetState
Dec 29 15:55:57 rivoplus volumio[3266]: info: Listing playlists
Dec 29 15:55:57 rivoplus volumio[3266]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Dec 29 15:55:57 rivoplus volumio[3266]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Dec 29 15:55:57 rivoplus volumio[3266]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache
Dec 29 15:55:58 rivoplus volumio[3266]: info: CoreCommandRouter::volumioGetState
Dec 29 15:55:58 rivoplus volumio[3266]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard
Dec 29 15:55:59 rivoplus volumio[3266]: info: CoreCommandRouter::volumioGetQueue
Dec 29 15:55:59 rivoplus volumio[3266]: info: CoreStateMachine::getQueue
Dec 29 15:55:59 rivoplus volumio[3266]: info: CorePlayQueue::getQueue
Dec 29 15:55:59 rivoplus volumio[3266]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Dec 29 15:55:59 rivoplus volumio[3266]: info: Received Get System Info
Dec 29 15:55:59 rivoplus volumio[3266]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Dec 29 15:55:59 rivoplus volumio[3266]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Dec 29 15:55:59 rivoplus volumio[3266]: info: Discovery: Getting this device information
Dec 29 15:55:59 rivoplus volumio[3266]: info: CoreCommandRouter::volumioGetState
Dec 29 15:55:59 rivoplus volumio[3266]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Dec 29 15:56:00 rivoplus volumio[3266]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Dec 29 15:56:00 rivoplus volumio[3266]: info: Received Get System Info
Dec 29 15:56:00 rivoplus volumio[3266]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Dec 29 15:56:00 rivoplus volumio[3266]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Dec 29 15:56:00 rivoplus volumio[3266]: info: Discovery: Getting this device information
Dec 29 15:56:00 rivoplus volumio[3266]: info: CoreCommandRouter::volumioGetState
Dec 29 15:56:00 rivoplus volumio[3266]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Dec 29 15:56:02 rivoplus volumio[3266]: info: CoreCommandRouter::volumioGetState
Dec 29 15:56:07 rivoplus volumio[3266]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
Dec 29 15:56:09 rivoplus volumio[3266]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri
Dec 29 15:56:09 rivoplus volumio[3266]: info: browseTIDALUri took 128 milliseconds
Dec 29 15:56:10 rivoplus volumio[3266]: info: Preload queue cleared
Dec 29 15:56:10 rivoplus volumio[3266]: info: Preload queue cleared
Dec 29 15:56:15 rivoplus volumio[3266]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Dec 29 15:56:15 rivoplus volumio[3266]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined
Dec 29 15:56:15 rivoplus volumio[3266]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates
Dec 29 15:56:15 rivoplus volumio[3266]: info: Received Get System Version
Dec 29 15:56:15 rivoplus volumio[3266]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Dec 29 15:56:15 rivoplus volumio[3266]: info: Received Get System Info
Dec 29 15:56:15 rivoplus volumio[3266]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Dec 29 15:56:15 rivoplus volumio[3266]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Dec 29 15:56:15 rivoplus volumio[3266]: info: Discovery: Getting this device information
Dec 29 15:56:15 rivoplus volumio[3266]: info: CoreCommandRouter::volumioGetState
Dec 29 15:56:15 rivoplus volumio[3266]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Dec 29 15:56:18 rivoplus volumio[3266]: info: CoreCommandRouter::executeOnPlugin: metavolumio , superSearch
Dec 29 15:56:18 rivoplus volumio[3266]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Dec 29 15:56:18 rivoplus volumio[3266]: TypeError: Cannot read property 'then' of undefined
Dec 29 15:56:18 rivoplus volumio[3266]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:498:20)
Dec 29 15:56:18 rivoplus volumio[3266]: at Socket.emit (events.js:400:28)
Dec 29 15:56:18 rivoplus volumio[3266]: at /volumio/node_modules/socket.io/lib/socket.js:528:12
Dec 29 15:56:18 rivoplus volumio[3266]: at processTicksAndRejections (internal/process/task_queues.js:77:11)
Dec 29 15:56:18 rivoplus volumio[3266]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Dec 29 15:56:19 rivoplus sudo[9278]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-29 15:55
Dec 29 15:56:19 rivoplus sudo[9278]: pam_unix(sudo:session): session opened for user root by (uid=0)
PRETTY_NAME="Debian GNU/Linux 10 (buster)"
NAME="Debian GNU/Linux"
VERSION_ID="10"
VERSION="10 (buster)"
VERSION_CODENAME=buster
ID=debian
HOME_URL="https://www.debian.org/"
SUPPORT_URL="https://www.debian.org/support"
BUG_REPORT_URL="https://bugs.debian.org/"
VOLUMIO_BUILD_VERSION="cc50ad4a2058d01de272214eb33827883bd2b7d8"
VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd"
VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2"
VOLUMIO_BE_VERSION="502750e5d54ecf269d25125b622bfabc030fb553"
VOLUMIO_ARCH="armv7"
VOLUMIO_VARIANT="rivoplus"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Wed 12 Nov 2025 07:44:55 PM CET"
VOLUMIO_VERSION="3.874"
VOLUMIO_HARDWARE="mp1"
VOLUMIO_DEVICENAME="Volumio MP1"
VOLUMIO_VENDOR_MODEL="Volumio Rivo Plus"
VOLUMIO_VENDOR="Volumio"
VOLUMIO_MODEL="Rivo Plus"
VOLUMIO_HASH="af8df74278231e4f9fb9f04db56c320f"