-- Logs begin at Tue 2026-01-27 06:22:26 EST, end at Wed 2026-01-28 04:13:47 EST. -- Jan 28 04:12:00 dolores volumio[17741]: info: CoreCommandRouter::volumioPlay Jan 28 04:12:00 dolores volumio[17741]: info: CoreStateMachine::play index undefined Jan 28 04:12:00 dolores volumio[17741]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 04:12:00 dolores volumio[17741]: info: CorePlayQueue::getTrack 0 Jan 28 04:12:00 dolores volumio[17741]: info: CoreStateMachine::startPlaybackTimer Jan 28 04:12:00 dolores volumio[17741]: info: CorePlayQueue::getTrack 0 Jan 28 04:12:00 dolores volumio[17741]: info: [1769591520701] ControllerTidal::clearAddPlayTrack Jan 28 04:12:00 dolores volumio[17741]: info: Getting stream with soundQuality HIGH Jan 28 04:12:00 dolores volumio[17741]: verbose: ControllerMpd::sendMpdCommand stop Jan 28 04:12:00 dolores volumio[17741]: info: sendMpdCommand stop took 1 milliseconds Jan 28 04:12:00 dolores volumio[17741]: verbose: ControllerMpd::sendMpdCommand clear Jan 28 04:12:00 dolores volumio[17741]: info: Jan 28 04:12:00 dolores volumio[17741]: ---------------------------- MPD announces system playlist update Jan 28 04:12:00 dolores volumio[17741]: info: Ignoring MPD Status Update Jan 28 04:12:00 dolores volumio[17741]: info: sendMpdCommand clear took 2 milliseconds Jan 28 04:12:00 dolores volumio[17741]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/9787448&soundQuality=HIGH" Jan 28 04:12:00 dolores volumio[17741]: error: updateQueue error: null Jan 28 04:12:00 dolores volumio[17741]: info: Jan 28 04:12:00 dolores volumio[17741]: ---------------------------- MPD announces system playlist update Jan 28 04:12:00 dolores volumio[17741]: info: Ignoring MPD Status Update Jan 28 04:12:00 dolores volumio[17741]: info: ------------------------------ 4ms Jan 28 04:12:00 dolores volumio[17741]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/9787448&soundQuality=HIGH" took 2 milliseconds Jan 28 04:12:00 dolores volumio[17741]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 28 04:12:00 dolores volumio[17741]: verbose: ControllerMpd::sendMpdCommand play Jan 28 04:12:00 dolores volumio[17741]: info: Jan 28 04:12:00 dolores volumio[17741]: ---------------------------- MPD announces system playlist update Jan 28 04:12:00 dolores volumio[17741]: info: Ignoring MPD Status Update Jan 28 04:12:00 dolores volumio[17741]: info: Jan 28 04:12:00 dolores volumio[17741]: ---------------------------- MPD announces system playlist update Jan 28 04:12:00 dolores volumio[17741]: info: Ignoring MPD Status Update Jan 28 04:12:00 dolores volumio[17741]: STREAMING PROXY: Handling url /?data=tidal://song/9787448&soundQuality=HIGH Jan 28 04:12:00 dolores volumio[17741]: info: ------------------------------ 7ms Jan 28 04:12:00 dolores volumio[17741]: info: sendMpdCommand play took 5 milliseconds Jan 28 04:12:00 dolores volumio[17741]: info: ------------------------------ 5ms Jan 28 04:12:00 dolores volumio[17741]: info: ------------------------------ 3ms Jan 28 04:12:00 dolores volumio[17741]: info: Jan 28 04:12:00 dolores volumio[17741]: ---------------------------- MPD announces system playlist update Jan 28 04:12:00 dolores volumio[17741]: info: Ignoring MPD Status Update Jan 28 04:12:00 dolores volumio[17741]: info: Jan 28 04:12:00 dolores volumio[17741]: ---------------------------- MPD announces system playlist update Jan 28 04:12:00 dolores volumio[17741]: info: Ignoring MPD Status Update Jan 28 04:12:00 dolores volumio[17741]: info: ------------------------------ 4ms Jan 28 04:12:00 dolores volumio[17741]: info: ------------------------------ 2ms Jan 28 04:12:00 dolores volumio[17741]: info: Executing endpoint getStreamUrltidal Jan 28 04:12:00 dolores volumio[17741]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Jan 28 04:12:00 dolores volumio[17741]: info: getStreamUrl took 197 milliseconds Jan 28 04:12:00 dolores volumio[17741]: STREAMING PROXY: Got real url: http://amz-pr-fa.audio.tidal.com/8447879/3b21c577a37f3bd6bb2bfe4b71bbf6aa_36.m4a?token=1769595120~ZTY4YWVmNjI0Y2IxZWY0YTE3ODQxZTJjZTEyMjEzYjExMzRjZDI4NA== Jan 28 04:12:01 dolores volumio[17741]: STREAMING PROXY: Response: 200, length: 2726166 Jan 28 04:12:01 dolores volumio[17741]: info: Jan 28 04:12:01 dolores volumio[17741]: ---------------------------- MPD announces state update: player Jan 28 04:12:01 dolores kernel: aml_tdm_open Jan 28 04:12:01 dolores kernel: Not init audio effects Jan 28 04:12:01 dolores kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Jan 28 04:12:01 dolores volumio[17741]: info: ControllerMpd::getState Jan 28 04:12:01 dolores volumio[17741]: verbose: ControllerMpd::sendMpdCommand status Jan 28 04:12:01 dolores volumio[17741]: info: Jan 28 04:12:01 dolores volumio[17741]: ---------------------------- MPD announces state update: player Jan 28 04:12:01 dolores volumio[17741]: info: ControllerMpd::getState Jan 28 04:12:01 dolores volumio[17741]: verbose: ControllerMpd::sendMpdCommand status Jan 28 04:12:01 dolores volumio[17741]: info: Jan 28 04:12:01 dolores volumio[17741]: ---------------------------- MPD announces state update: player Jan 28 04:12:01 dolores volumio[17741]: info: ControllerMpd::getState Jan 28 04:12:01 dolores volumio[17741]: verbose: ControllerMpd::sendMpdCommand status Jan 28 04:12:01 dolores kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Jan 28 04:12:01 dolores kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Jan 28 04:12:01 dolores kernel: set mclk:49152000, mpll:98304000, get mclk:49151901, mpll:98303801 Jan 28 04:12:01 dolores kernel: asoc aml_dai_set_tdm_fmt, 0x4001, ffffffc0503e7c18, id(1), clksel(1) Jan 28 04:12:01 dolores kernel: aml_dai_set_tdm_fmt(), fmt not change Jan 28 04:12:01 dolores kernel: dump_pcm_setting(ffffffc0503e7c18) Jan 28 04:12:01 dolores kernel: pcm_mode(1) Jan 28 04:12:01 dolores kernel: sysclk(49152000) Jan 28 04:12:01 dolores kernel: sysclk_bclk_ratio(4) Jan 28 04:12:01 dolores kernel: bclk(12288000) Jan 28 04:12:01 dolores kernel: bclk_lrclk_ratio(64) Jan 28 04:12:01 dolores kernel: lrclk(192000) Jan 28 04:12:01 dolores kernel: tx_mask(0x3) Jan 28 04:12:01 dolores kernel: rx_mask(0x3) Jan 28 04:12:01 dolores kernel: slots(2) Jan 28 04:12:01 dolores kernel: slot_width(32) Jan 28 04:12:01 dolores kernel: lane_mask_in(0x2) Jan 28 04:12:01 dolores kernel: lane_mask_out(0x1) Jan 28 04:12:01 dolores kernel: lane_oe_mask_in(0x0) Jan 28 04:12:01 dolores kernel: lane_oe_mask_out(0x0) Jan 28 04:12:01 dolores kernel: lane_lb_mask_in(0x0) Jan 28 04:12:01 dolores kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Jan 28 04:12:01 dolores kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Jan 28 04:12:01 dolores kernel: set mclk:49152000, mpll:98304000, get mclk:49151901, mpll:98303801 Jan 28 04:12:01 dolores kernel: aml_dai_set_clkdiv, div 4, clksel(1) Jan 28 04:12:01 dolores kernel: aml_dai_set_bclk_ratio, select I2S mode Jan 28 04:12:01 dolores kernel: aml_dai_tdm_hw_params(), enable mclk for TDM-B Jan 28 04:12:01 dolores kernel: aml_tdm_prepare(), reset fddr Jan 28 04:12:01 dolores kernel: spdif_a fifo ctrl, frddr:0 type:1, 16 bits, chmask 0x3, swap 0x10 Jan 28 04:12:01 dolores kernel: spdif_info: rate: 192000, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0xe00, ch1_r:0xe00 Jan 28 04:12:01 dolores kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Jan 28 04:12:01 dolores kernel: tdm playback mute: 0, lane_cnt = 8 Jan 28 04:12:01 dolores volumio[17741]: info: sendMpdCommand status took 8 milliseconds Jan 28 04:12:01 dolores volumio[17741]: verbose: ControllerMpd::parseState Jan 28 04:12:01 dolores volumio[17741]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 04:12:01 dolores volumio[17741]: info: Jan 28 04:12:01 dolores volumio[17741]: ---------------------------- MPD announces state update: player Jan 28 04:12:01 dolores volumio[17741]: info: ControllerMpd::getState Jan 28 04:12:01 dolores volumio[17741]: verbose: ControllerMpd::sendMpdCommand status Jan 28 04:12:01 dolores volumio[17741]: info: sendMpdCommand status took 10 milliseconds Jan 28 04:12:01 dolores volumio[17741]: info: sendMpdCommand status took 8 milliseconds Jan 28 04:12:01 dolores volumio[17741]: info: sendMpdCommand playlistinfo took 4 milliseconds Jan 28 04:12:01 dolores volumio[17741]: verbose: ControllerMpd::parseState Jan 28 04:12:01 dolores volumio[17741]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 04:12:01 dolores volumio[17741]: verbose: ControllerMpd::parseState Jan 28 04:12:01 dolores volumio[17741]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 04:12:01 dolores volumio[17741]: verbose: ControllerMpd::parseTrackInfo Jan 28 04:12:01 dolores volumio[17741]: info: ControllerMpd::pushState Jan 28 04:12:01 dolores volumio[17741]: info: CoreCommandRouter::servicePushState Jan 28 04:12:01 dolores volumio[17741]: info: CorePlayQueue::getTrack 0 Jan 28 04:12:01 dolores volumio[17741]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":224,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"9787448&soundQuality=HIGH","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/9787448&soundQuality=HIGH","trackType":"tidal"} Jan 28 04:12:01 dolores volumio[17741]: verbose: CURRENT POSITION 0 Jan 28 04:12:01 dolores volumio[17741]: info: CoreStateMachine::syncState stateService play Jan 28 04:12:01 dolores volumio[17741]: info: CoreStateMachine::syncState currentStatus stop Jan 28 04:12:01 dolores volumio[17741]: info: ------------------------------ 23ms Jan 28 04:12:01 dolores volumio[17741]: info: Jan 28 04:12:01 dolores volumio[17741]: ---------------------------- MPD announces state update: player Jan 28 04:12:01 dolores volumio[17741]: info: ControllerMpd::getState Jan 28 04:12:01 dolores volumio[17741]: verbose: ControllerMpd::sendMpdCommand status Jan 28 04:12:01 dolores volumio[17741]: info: Jan 28 04:12:01 dolores volumio[17741]: ---------------------------- MPD announces state update: player Jan 28 04:12:01 dolores volumio[17741]: info: ControllerMpd::getState Jan 28 04:12:01 dolores volumio[17741]: verbose: ControllerMpd::sendMpdCommand status Jan 28 04:12:01 dolores volumio[17741]: info: sendMpdCommand status took 18 milliseconds Jan 28 04:12:01 dolores volumio[17741]: info: sendMpdCommand playlistinfo took 16 milliseconds Jan 28 04:12:01 dolores volumio[17741]: info: sendMpdCommand status took 10 milliseconds Jan 28 04:12:01 dolores volumio[17741]: verbose: ControllerMpd::parseState Jan 28 04:12:01 dolores volumio[17741]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 04:12:01 dolores volumio[17741]: verbose: ControllerMpd::parseTrackInfo Jan 28 04:12:01 dolores volumio[17741]: verbose: ControllerMpd::parseState Jan 28 04:12:01 dolores volumio[17741]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 04:12:01 dolores volumio[17741]: info: ControllerMpd::pushState Jan 28 04:12:01 dolores volumio[17741]: info: CoreCommandRouter::servicePushState Jan 28 04:12:01 dolores volumio[17741]: info: CorePlayQueue::getTrack 0 Jan 28 04:12:01 dolores volumio[17741]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":224,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"9787448&soundQuality=HIGH","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/9787448&soundQuality=HIGH","trackType":"tidal"} Jan 28 04:12:01 dolores volumio[17741]: verbose: CURRENT POSITION 0 Jan 28 04:12:01 dolores volumio[17741]: info: CoreStateMachine::syncState stateService play Jan 28 04:12:01 dolores volumio[17741]: info: CoreStateMachine::syncState currentStatus play Jan 28 04:12:01 dolores volumio[17741]: info: Received an update from plugin. extracting info from payload Jan 28 04:12:01 dolores volumio[17741]: info: CoreStateMachine::pushState Jan 28 04:12:01 dolores volumio[17741]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 04:12:01 dolores volumio[17741]: info: CoreCommandRouter::volumioPushState Jan 28 04:12:01 dolores volumio[17741]: info: CoreStateMachine::pushState Jan 28 04:12:01 dolores volumio[17741]: info: CoreCommandRouter::volumioPushState Jan 28 04:12:01 dolores volumio[17741]: info: ControllerMpd::pushState Jan 28 04:12:01 dolores volumio[17741]: info: CoreCommandRouter::servicePushState Jan 28 04:12:01 dolores volumio[17741]: info: CorePlayQueue::getTrack 0 Jan 28 04:12:01 dolores volumio[17741]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":224,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"9787448&soundQuality=HIGH","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/9787448&soundQuality=HIGH","trackType":"tidal"} Jan 28 04:12:01 dolores volumio[17741]: verbose: CURRENT POSITION 0 Jan 28 04:12:01 dolores volumio[17741]: info: CoreStateMachine::syncState stateService play Jan 28 04:12:01 dolores volumio[17741]: info: CoreStateMachine::syncState currentStatus play Jan 28 04:12:01 dolores volumio[17741]: info: Received an update from plugin. extracting info from payload Jan 28 04:12:01 dolores volumio[17741]: info: CoreStateMachine::pushState Jan 28 04:12:01 dolores volumio[17741]: info: CoreCommandRouter::volumioPushState Jan 28 04:12:01 dolores volumio[17741]: info: CoreStateMachine::pushState Jan 28 04:12:01 dolores volumio[17741]: info: CoreCommandRouter::volumioPushState Jan 28 04:12:01 dolores volumio[17741]: info: ------------------------------ 91ms Jan 28 04:12:01 dolores volumio[17741]: info: ------------------------------ 90ms Jan 28 04:12:01 dolores volumio[17741]: info: sendMpdCommand status took 72 milliseconds Jan 28 04:12:01 dolores volumio[17741]: info: sendMpdCommand playlistinfo took 64 milliseconds Jan 28 04:12:01 dolores volumio[17741]: info: sendMpdCommand playlistinfo took 63 milliseconds Jan 28 04:12:01 dolores volumio[17741]: verbose: ControllerMpd::parseState Jan 28 04:12:01 dolores volumio[17741]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 04:12:01 dolores volumio[17741]: verbose: ControllerMpd::parseTrackInfo Jan 28 04:12:01 dolores volumio[17741]: verbose: ControllerMpd::parseTrackInfo Jan 28 04:12:01 dolores volumio[17741]: info: ControllerMpd::pushState Jan 28 04:12:01 dolores volumio[17741]: info: CoreCommandRouter::servicePushState Jan 28 04:12:01 dolores volumio[17741]: info: CorePlayQueue::getTrack 0 Jan 28 04:12:01 dolores volumio[17741]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":224,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"9787448&soundQuality=HIGH","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/9787448&soundQuality=HIGH","trackType":"tidal"} Jan 28 04:12:01 dolores volumio[17741]: verbose: CURRENT POSITION 0 Jan 28 04:12:01 dolores volumio[17741]: info: CoreStateMachine::syncState stateService play Jan 28 04:12:01 dolores volumio[17741]: info: CoreStateMachine::syncState currentStatus play Jan 28 04:12:01 dolores volumio[17741]: info: Received an update from plugin. extracting info from payload Jan 28 04:12:01 dolores volumio[17741]: info: CoreStateMachine::pushState Jan 28 04:12:01 dolores volumio[17741]: info: CoreCommandRouter::volumioPushState Jan 28 04:12:01 dolores volumio[17741]: info: CoreStateMachine::pushState Jan 28 04:12:01 dolores volumio[17741]: info: CoreCommandRouter::volumioPushState Jan 28 04:12:01 dolores volumio[17741]: info: ControllerMpd::pushState Jan 28 04:12:01 dolores volumio[17741]: info: CoreCommandRouter::servicePushState Jan 28 04:12:01 dolores volumio[17741]: info: CorePlayQueue::getTrack 0 Jan 28 04:12:01 dolores volumio[17741]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":224,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"9787448&soundQuality=HIGH","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/9787448&soundQuality=HIGH","trackType":"tidal"} Jan 28 04:12:01 dolores volumio[17741]: verbose: CURRENT POSITION 0 Jan 28 04:12:01 dolores volumio[17741]: info: CoreStateMachine::syncState stateService play Jan 28 04:12:01 dolores volumio[17741]: info: CoreStateMachine::syncState currentStatus play Jan 28 04:12:01 dolores volumio[17741]: info: Received an update from plugin. extracting info from payload Jan 28 04:12:01 dolores volumio[17741]: info: CoreStateMachine::pushState Jan 28 04:12:01 dolores volumio[17741]: info: CoreCommandRouter::volumioPushState Jan 28 04:12:01 dolores volumio[17741]: info: CoreStateMachine::pushState Jan 28 04:12:01 dolores volumio[17741]: info: CoreCommandRouter::volumioPushState Jan 28 04:12:01 dolores volumio[17741]: info: ------------------------------ 161ms Jan 28 04:12:01 dolores volumio[17741]: info: ------------------------------ 164ms Jan 28 04:12:01 dolores volumio[17741]: info: Signalling Playback active due to playback status change Jan 28 04:12:01 dolores volumio[17741]: info: Signalling Playback active due to playback status change Jan 28 04:12:01 dolores volumio[17741]: info: Signalling Playback active due to playback status change Jan 28 04:12:01 dolores volumio[17741]: STREAMING PROXY: Client dropped request, destroying Jan 28 04:12:01 dolores volumio[17741]: info: Signalling Playback active due to playback status change Jan 28 04:12:01 dolores volumio[17741]: info: Signalling Playback active due to playback status change Jan 28 04:12:01 dolores volumio[17741]: info: Signalling Playback active due to playback status change Jan 28 04:12:01 dolores volumio[17741]: info: Signalling Playback active due to playback status change Jan 28 04:12:01 dolores volumio[17741]: info: Signalling Playback active due to playback status change Jan 28 04:12:01 dolores volumio[17741]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Jan 28 04:12:01 dolores volumio[17741]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Jan 28 04:12:01 dolores volumio[17741]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Jan 28 04:12:01 dolores volumio[17741]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Jan 28 04:12:01 dolores volumio[17741]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Jan 28 04:12:01 dolores volumio[17741]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Jan 28 04:12:01 dolores volumio[17741]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Jan 28 04:12:01 dolores volumio[17741]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Jan 28 04:12:01 dolores volumio[17741]: info: sendMpdCommand playlistinfo took 154 milliseconds Jan 28 04:12:01 dolores volumio[17741]: verbose: ControllerMpd::parseTrackInfo Jan 28 04:12:01 dolores volumio[17741]: info: ControllerMpd::pushState Jan 28 04:12:01 dolores volumio[17741]: info: CoreCommandRouter::servicePushState Jan 28 04:12:01 dolores volumio[17741]: info: CorePlayQueue::getTrack 0 Jan 28 04:12:01 dolores volumio[17741]: verbose: STATE SERVICE {"status":"play","position":0,"seek":45,"duration":224,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"9787448&soundQuality=HIGH","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/9787448&soundQuality=HIGH","trackType":"tidal"} Jan 28 04:12:01 dolores volumio[17741]: verbose: CURRENT POSITION 0 Jan 28 04:12:01 dolores volumio[17741]: info: CoreStateMachine::syncState stateService play Jan 28 04:12:01 dolores volumio[17741]: info: CoreStateMachine::syncState currentStatus play Jan 28 04:12:01 dolores volumio[17741]: info: Received an update from plugin. extracting info from payload Jan 28 04:12:01 dolores volumio[17741]: info: CoreStateMachine::pushState Jan 28 04:12:01 dolores volumio[17741]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 04:12:01 dolores volumio[17741]: info: CoreCommandRouter::volumioPushState Jan 28 04:12:01 dolores volumio[17741]: info: CoreStateMachine::pushState Jan 28 04:12:01 dolores volumio[17741]: info: CoreCommandRouter::volumioPushState Jan 28 04:12:01 dolores volumio[17741]: info: ------------------------------ 259ms Jan 28 04:12:01 dolores volumio[17741]: info: Signalling Playback active due to playback status change Jan 28 04:12:01 dolores volumio[17741]: info: Signalling Playback active due to playback status change Jan 28 04:12:01 dolores volumio[17741]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Jan 28 04:12:01 dolores volumio[17741]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Jan 28 04:12:01 dolores volumio[17741]: info: MCU Signalled Playback Active Jan 28 04:12:01 dolores kernel: asoc-aml-card auge_sound: tdm playback enable Jan 28 04:12:01 dolores kernel: spdif_a is set to enable Jan 28 04:12:03 dolores volumio5-onboarding[3837]: time=2026-01-28T04:12:03.221-05:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=74 Jan 28 04:12:04 dolores volumio5-onboarding[3837]: time=2026-01-28T04:12:04.405-05:00 level=WARN msg="received acknowledgment for source with no pending writes" component=conn/ble data=4a00 addr=74 Jan 28 04:12:10 dolores volumio5-onboarding[3837]: time=2026-01-28T04:12:10.035-05:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=74 Jan 28 04:12:10 dolores volumio5-onboarding[3837]: time=2026-01-28T04:12:10.344-05:00 level=WARN msg="received acknowledgment for source with no pending writes" component=conn/ble data=4a00 addr=74 Jan 28 04:12:18 dolores go-librespot[17993]: time="2026-01-28T04:12:18-05:00" level=trace msg="received accesspoint ping" Jan 28 04:12:18 dolores go-librespot[17993]: time="2026-01-28T04:12:18-05:00" level=trace msg="received accesspoint pong ack" Jan 28 04:12:19 dolores go-librespot[17993]: time="2026-01-28T04:12:19-05:00" level=trace msg="sent dealer ping" Jan 28 04:12:19 dolores go-librespot[17993]: time="2026-01-28T04:12:19-05:00" level=trace msg="received dealer pong" Jan 28 04:12:20 dolores volumio[17741]: Searching plugin music_service/tidal Jan 28 04:12:20 dolores volumio[17741]: info: CoreCommandRouter::executeOnPlugin: tidal , search Jan 28 04:12:21 dolores volumio[17741]: info: searchTIDALUri took 315 milliseconds Jan 28 04:12:21 dolores volumio[17741]: info: search took 318 milliseconds Jan 28 04:12:21 dolores volumio[17741]: info: All search sources collected, pushing search results Jan 28 04:12:21 dolores volumio[17741]: Searching plugin music_service/tidal Jan 28 04:12:21 dolores volumio[17741]: info: CoreCommandRouter::executeOnPlugin: tidal , search Jan 28 04:12:21 dolores volumio[17741]: info: searchTIDALUri took 294 milliseconds Jan 28 04:12:21 dolores volumio[17741]: info: search took 295 milliseconds Jan 28 04:12:21 dolores volumio[17741]: info: All search sources collected, pushing search results Jan 28 04:12:24 dolores volumio[17741]: Searching plugin music_service/tidal Jan 28 04:12:24 dolores volumio[17741]: info: CoreCommandRouter::executeOnPlugin: tidal , search Jan 28 04:12:24 dolores volumio[17741]: info: searchTIDALUri took 383 milliseconds Jan 28 04:12:24 dolores volumio[17741]: info: search took 386 milliseconds Jan 28 04:12:24 dolores volumio[17741]: info: All search sources collected, pushing search results Jan 28 04:12:25 dolores volumio[17741]: Searching plugin music_service/tidal Jan 28 04:12:25 dolores volumio[17741]: info: CoreCommandRouter::executeOnPlugin: tidal , search Jan 28 04:12:25 dolores volumio[17741]: info: searchTIDALUri took 374 milliseconds Jan 28 04:12:25 dolores volumio[17741]: info: search took 376 milliseconds Jan 28 04:12:25 dolores volumio[17741]: info: All search sources collected, pushing search results Jan 28 04:12:28 dolores volumio[17741]: Searching plugin music_service/tidal Jan 28 04:12:28 dolores volumio[17741]: info: CoreCommandRouter::executeOnPlugin: tidal , search Jan 28 04:12:28 dolores volumio[17741]: info: searchTIDALUri took 219 milliseconds Jan 28 04:12:28 dolores volumio[17741]: info: search took 220 milliseconds Jan 28 04:12:28 dolores volumio[17741]: info: All search sources collected, pushing search results Jan 28 04:12:31 dolores volumio[17741]: Searching plugin music_service/tidal Jan 28 04:12:31 dolores volumio[17741]: info: CoreCommandRouter::executeOnPlugin: tidal , search Jan 28 04:12:31 dolores volumio[17741]: info: searchTIDALUri took 268 milliseconds Jan 28 04:12:31 dolores volumio[17741]: info: search took 269 milliseconds Jan 28 04:12:31 dolores volumio[17741]: info: All search sources collected, pushing search results Jan 28 04:12:32 dolores volumio[17741]: Searching plugin music_service/tidal Jan 28 04:12:32 dolores volumio[17741]: info: CoreCommandRouter::executeOnPlugin: tidal , search Jan 28 04:12:32 dolores volumio[17741]: info: searchTIDALUri took 208 milliseconds Jan 28 04:12:32 dolores volumio[17741]: info: search took 209 milliseconds Jan 28 04:12:49 dolores go-librespot[17993]: time="2026-01-28T04:12:49-05:00" level=trace msg="sent dealer ping" Jan 28 04:12:49 dolores go-librespot[17993]: time="2026-01-28T04:12:49-05:00" level=trace msg="received dealer pong" Jan 28 04:12:53 dolores volumio[17741]: Searching plugin music_service/tidal Jan 28 04:12:53 dolores volumio[17741]: info: CoreCommandRouter::executeOnPlugin: tidal , search Jan 28 04:12:53 dolores volumio[17741]: info: searchTIDALUri took 366 milliseconds Jan 28 04:12:53 dolores volumio[17741]: info: search took 368 milliseconds Jan 28 04:12:53 dolores volumio[17741]: info: All search sources collected, pushing search results Jan 28 04:12:59 dolores volumio[17741]: Searching plugin music_service/tidal Jan 28 04:12:59 dolores volumio[17741]: info: CoreCommandRouter::executeOnPlugin: tidal , search Jan 28 04:12:59 dolores volumio[17741]: info: searchTIDALUri took 378 milliseconds Jan 28 04:12:59 dolores volumio[17741]: info: search took 380 milliseconds Jan 28 04:12:59 dolores volumio[17741]: info: All search sources collected, pushing search results Jan 28 04:13:02 dolores volumio[17741]: Searching plugin music_service/tidal Jan 28 04:13:02 dolores volumio[17741]: info: CoreCommandRouter::executeOnPlugin: tidal , search Jan 28 04:13:03 dolores volumio[17741]: info: searchTIDALUri took 382 milliseconds Jan 28 04:13:03 dolores volumio[17741]: info: search took 383 milliseconds Jan 28 04:13:03 dolores volumio[17741]: info: All search sources collected, pushing search results Jan 28 04:13:04 dolores volumio[17741]: Searching plugin music_service/tidal Jan 28 04:13:04 dolores volumio[17741]: info: CoreCommandRouter::executeOnPlugin: tidal , search Jan 28 04:13:05 dolores volumio[17741]: info: searchTIDALUri took 225 milliseconds Jan 28 04:13:05 dolores volumio[17741]: info: search took 226 milliseconds Jan 28 04:13:05 dolores volumio[17741]: info: All search sources collected, pushing search results Jan 28 04:13:10 dolores volumio[17741]: Searching plugin music_service/tidal Jan 28 04:13:10 dolores volumio[17741]: info: CoreCommandRouter::executeOnPlugin: tidal , search Jan 28 04:13:10 dolores volumio[17741]: info: searchTIDALUri took 225 milliseconds Jan 28 04:13:10 dolores volumio[17741]: info: search took 226 milliseconds Jan 28 04:13:10 dolores volumio[17741]: info: All search sources collected, pushing search results Jan 28 04:13:11 dolores volumio[17741]: Searching plugin music_service/tidal Jan 28 04:13:11 dolores volumio[17741]: info: CoreCommandRouter::executeOnPlugin: tidal , search Jan 28 04:13:11 dolores volumio[17741]: info: searchTIDALUri took 251 milliseconds Jan 28 04:13:11 dolores volumio[17741]: info: search took 251 milliseconds Jan 28 04:13:19 dolores go-librespot[17993]: time="2026-01-28T04:13:19-05:00" level=trace msg="sent dealer ping" Jan 28 04:13:19 dolores go-librespot[17993]: time="2026-01-28T04:13:19-05:00" level=trace msg="received dealer pong" Jan 28 04:13:32 dolores volumio[17741]: Searching plugin music_service/tidal Jan 28 04:13:32 dolores volumio[17741]: info: CoreCommandRouter::executeOnPlugin: tidal , search Jan 28 04:13:32 dolores volumio[17741]: info: searchTIDALUri took 311 milliseconds Jan 28 04:13:32 dolores volumio[17741]: info: search took 312 milliseconds Jan 28 04:13:32 dolores volumio[17741]: info: All search sources collected, pushing search results Jan 28 04:13:33 dolores volumio[17741]: Searching plugin music_service/tidal Jan 28 04:13:33 dolores volumio[17741]: info: CoreCommandRouter::executeOnPlugin: tidal , search Jan 28 04:13:33 dolores volumio[17741]: info: searchTIDALUri took 371 milliseconds Jan 28 04:13:33 dolores volumio[17741]: info: search took 371 milliseconds Jan 28 04:13:33 dolores volumio[17741]: info: All search sources collected, pushing search results Jan 28 04:13:34 dolores volumio[17741]: Searching plugin music_service/tidal Jan 28 04:13:34 dolores volumio[17741]: info: CoreCommandRouter::executeOnPlugin: tidal , search Jan 28 04:13:34 dolores volumio[17741]: info: searchTIDALUri took 286 milliseconds Jan 28 04:13:34 dolores volumio[17741]: info: search took 287 milliseconds Jan 28 04:13:34 dolores volumio[17741]: info: All search sources collected, pushing search results Jan 28 04:13:42 dolores volumio[17741]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Jan 28 04:13:43 dolores volumio[17741]: info: browseTIDALUri took 912 milliseconds Jan 28 04:13:43 dolores volumio[17741]: info: Preload queue cleared Jan 28 04:13:43 dolores volumio[17741]: info: Preload queue cleared Jan 28 04:13:43 dolores volumio[17741]: info: Preload queue cleared Jan 28 04:13:43 dolores volumio[17741]: info: Preloading song: tidal://song/199662989 Jan 28 04:13:43 dolores volumio[17741]: info: Preloading song: tidal://song/212679223 Jan 28 04:13:43 dolores volumio[17741]: info: Preloading song: tidal://song/74332216 Jan 28 04:13:43 dolores volumio[17741]: info: Preloading song: tidal://song/143635771 Jan 28 04:13:43 dolores volumio[17741]: info: Preloading song: tidal://song/457181227 Jan 28 04:13:43 dolores volumio[17741]: info: Preloading song: tidal://song/203941189 Jan 28 04:13:43 dolores volumio[17741]: info: Preloading song: tidal://song/68676475 Jan 28 04:13:43 dolores volumio[17741]: info: Preloading song: tidal://song/3082187 Jan 28 04:13:43 dolores volumio[17741]: info: Preloading song: tidal://song/199662988 Jan 28 04:13:43 dolores volumio[17741]: info: Preloading song: tidal://song/193188795 Jan 28 04:13:43 dolores volumio[17741]: info: Preloading song: tidal://song/86130850 Jan 28 04:13:43 dolores volumio[17741]: info: Preloading song: tidal://song/67280602 Jan 28 04:13:43 dolores volumio[17741]: info: Preloading song: tidal://song/244447261 Jan 28 04:13:43 dolores volumio[17741]: info: Preloading song: tidal://song/154701782 Jan 28 04:13:43 dolores volumio[17741]: info: Preloading song: tidal://song/483193617 Jan 28 04:13:43 dolores volumio[17741]: info: Preloading song: tidal://song/481782020 Jan 28 04:13:43 dolores volumio[17741]: info: Preloading song: tidal://song/154701779 Jan 28 04:13:43 dolores volumio[17741]: info: Preloading song: tidal://song/476664851 Jan 28 04:13:43 dolores volumio[17741]: info: Preloading song: tidal://song/182521345 Jan 28 04:13:43 dolores volumio[17741]: info: Preloading song: tidal://song/47683234 Jan 28 04:13:43 dolores volumio[17741]: info: Preloading song: tidal://song/109276422 Jan 28 04:13:43 dolores volumio[17741]: info: Preloading song: tidal://song/91762104 Jan 28 04:13:43 dolores volumio[17741]: info: Preloading song: tidal://song/125937208 Jan 28 04:13:43 dolores volumio[17741]: info: Preloading song: tidal://song/476664852 Jan 28 04:13:43 dolores volumio[17741]: info: Preloading song: tidal://song/154701780 Jan 28 04:13:43 dolores volumio[17741]: info: Preloading song: tidal://song/63823216 Jan 28 04:13:43 dolores volumio[17741]: info: Preloading song: tidal://song/271167022 Jan 28 04:13:43 dolores volumio[17741]: info: Preloading song: tidal://song/476664850 Jan 28 04:13:43 dolores volumio[17741]: info: Preloading song: tidal://song/103844579 Jan 28 04:13:43 dolores volumio[17741]: info: Preloading song: tidal://song/186443687 Jan 28 04:13:43 dolores volumio[17741]: info: Preloading song: tidal://song/96692216 Jan 28 04:13:43 dolores volumio[17741]: info: Preloading song: tidal://song/186443690 Jan 28 04:13:43 dolores volumio[17741]: info: Preloading song: tidal://song/171847098 Jan 28 04:13:43 dolores volumio[17741]: info: Preloading song: tidal://song/476664855 Jan 28 04:13:43 dolores volumio[17741]: info: Preloading song: tidal://song/193188801 Jan 28 04:13:43 dolores volumio[17741]: info: Preloading song: tidal://song/154701781 Jan 28 04:13:43 dolores volumio[17741]: info: Preloading song: tidal://song/91762101 Jan 28 04:13:43 dolores volumio[17741]: info: Preloading song: tidal://song/91455449 Jan 28 04:13:43 dolores volumio[17741]: info: Preloading song: tidal://song/212679225 Jan 28 04:13:43 dolores volumio[17741]: info: Preloading song: tidal://song/476664853 Jan 28 04:13:43 dolores volumio[17741]: info: Preloading song: tidal://song/199662994 Jan 28 04:13:43 dolores volumio[17741]: info: Preloading song: tidal://song/476664854 Jan 28 04:13:43 dolores volumio[17741]: info: Preloading song: tidal://song/171847093 Jan 28 04:13:43 dolores volumio[17741]: info: Preloading song: tidal://song/197008269 Jan 28 04:13:43 dolores volumio[17741]: info: Preloading song: tidal://song/37598504 Jan 28 04:13:43 dolores volumio[17741]: info: Preloading song: tidal://song/154701784 Jan 28 04:13:43 dolores volumio[17741]: info: Preloading song: tidal://song/186443691 Jan 28 04:13:43 dolores volumio[17741]: info: Preloading song: tidal://song/212679226 Jan 28 04:13:43 dolores volumio[17741]: info: Preloading song: tidal://song/154701785 Jan 28 04:13:43 dolores volumio[17741]: info: Preloading song: tidal://song/176428312 Jan 28 04:13:43 dolores volumio[17741]: info: Exploding uri tidal://song/199662989 in service tidal Jan 28 04:13:43 dolores volumio[17741]: info: Exploding uri tidal://song/212679223 in service tidal Jan 28 04:13:43 dolores volumio[17741]: info: Exploding uri tidal://song/74332216 in service tidal Jan 28 04:13:43 dolores volumio[17741]: info: Exploding uri tidal://song/143635771 in service tidal Jan 28 04:13:43 dolores volumio[17741]: info: Executing endpoint getSimilarArtists Jan 28 04:13:43 dolores volumio[17741]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Jan 28 04:13:43 dolores volumio[17741]: info: Exploding uri tidal://song/457181227 in service tidal Jan 28 04:13:43 dolores volumio[17741]: info: Executing endpoint getSimilarArtists Jan 28 04:13:43 dolores volumio[17741]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Jan 28 04:13:43 dolores volumio[17741]: info: Executing endpoint getSimilarArtists Jan 28 04:13:43 dolores volumio[17741]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Jan 28 04:13:43 dolores volumio[17741]: info: Exploding uri tidal://song/203941189 in service tidal Jan 28 04:13:43 dolores volumio[17741]: info: Executing endpoint metavolumio Jan 28 04:13:43 dolores volumio[17741]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 28 04:13:43 dolores volumio[17741]: info: Exploding uri tidal://song/68676475 in service tidal Jan 28 04:13:43 dolores volumio[17741]: info: Executing endpoint metavolumio Jan 28 04:13:43 dolores volumio[17741]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 28 04:13:43 dolores volumio[17741]: info: Executing endpoint metavolumio Jan 28 04:13:43 dolores volumio[17741]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 28 04:13:43 dolores volumio[17741]: info: Exploding uri tidal://song/3082187 in service tidal Jan 28 04:13:43 dolores volumio[17741]: info: Exploding uri tidal://song/199662988 in service tidal Jan 28 04:13:43 dolores volumio[17741]: info: Exploding uri tidal://song/193188795 in service tidal Jan 28 04:13:44 dolores volumio[17741]: info: Exploding uri tidal://song/86130850 in service tidal Jan 28 04:13:44 dolores volumio[17741]: info: explodeTIDALUri took 462 milliseconds Jan 28 04:13:44 dolores volumio[17741]: info: explodeTIDALUri took 519 milliseconds Jan 28 04:13:44 dolores volumio[17741]: info: explodeTIDALUri took 443 milliseconds Jan 28 04:13:44 dolores volumio[17741]: info: Exploding uri tidal://song/67280602 in service tidal Jan 28 04:13:44 dolores volumio[17741]: info: Exploding uri tidal://song/244447261 in service tidal Jan 28 04:13:44 dolores volumio[17741]: info: explodeTIDALUri took 299 milliseconds Jan 28 04:13:44 dolores volumio[17741]: info: explodeTIDALUri took 270 milliseconds Jan 28 04:13:44 dolores volumio[17741]: info: explodeTIDALUri took 425 milliseconds Jan 28 04:13:44 dolores volumio[17741]: info: explodeTIDALUri took 488 milliseconds Jan 28 04:13:44 dolores volumio[17741]: info: explodeTIDALUri took 388 milliseconds Jan 28 04:13:44 dolores volumio[17741]: info: Exploding uri tidal://song/154701782 in service tidal Jan 28 04:13:44 dolores volumio[17741]: info: explodeTIDALUri took 240 milliseconds Jan 28 04:13:44 dolores volumio[17741]: info: explodeTIDALUri took 298 milliseconds Jan 28 04:13:44 dolores volumio[17741]: info: Exploding uri tidal://song/483193617 in service tidal Jan 28 04:13:44 dolores volumio[17741]: info: Exploding uri tidal://song/481782020 in service tidal Jan 28 04:13:44 dolores volumio[17741]: info: Exploding uri tidal://song/154701779 in service tidal Jan 28 04:13:44 dolores volumio[17741]: info: explodeTIDALUri took 333 milliseconds Jan 28 04:13:44 dolores volumio[17741]: info: Exploding uri tidal://song/476664851 in service tidal Jan 28 04:13:44 dolores volumio[17741]: info: explodeTIDALUri took 272 milliseconds Jan 28 04:13:44 dolores volumio[17741]: info: Exploding uri tidal://song/182521345 in service tidal Jan 28 04:13:44 dolores volumio[17741]: info: Exploding uri tidal://song/47683234 in service tidal Jan 28 04:13:44 dolores volumio[17741]: info: Exploding uri tidal://song/109276422 in service tidal Jan 28 04:13:44 dolores volumio[17741]: info: explodeTIDALUri took 425 milliseconds Jan 28 04:13:44 dolores volumio[17741]: info: explodeTIDALUri took 374 milliseconds Jan 28 04:13:44 dolores volumio[17741]: info: explodeTIDALUri took 227 milliseconds Jan 28 04:13:44 dolores volumio[17741]: info: explodeTIDALUri took 279 milliseconds Jan 28 04:13:44 dolores volumio[17741]: info: explodeTIDALUri took 336 milliseconds Jan 28 04:13:44 dolores volumio[17741]: info: Exploding uri tidal://song/91762104 in service tidal Jan 28 04:13:44 dolores volumio[17741]: info: explodeTIDALUri took 228 milliseconds Jan 28 04:13:44 dolores volumio[17741]: info: Exploding uri tidal://song/125937208 in service tidal Jan 28 04:13:44 dolores volumio[17741]: info: Exploding uri tidal://song/476664852 in service tidal Jan 28 04:13:44 dolores volumio[17741]: info: explodeTIDALUri took 216 milliseconds Jan 28 04:13:44 dolores volumio[17741]: info: explodeTIDALUri took 254 milliseconds Jan 28 04:13:44 dolores volumio[17741]: info: Exploding uri tidal://song/154701780 in service tidal Jan 28 04:13:44 dolores volumio[17741]: info: explodeTIDALUri took 228 milliseconds Jan 28 04:13:44 dolores volumio[17741]: info: explodeTIDALUri took 196 milliseconds Jan 28 04:13:44 dolores volumio[17741]: info: Exploding uri tidal://song/63823216 in service tidal Jan 28 04:13:44 dolores volumio[17741]: info: explodeTIDALUri took 172 milliseconds Jan 28 04:13:44 dolores volumio[17741]: info: Exploding uri tidal://song/271167022 in service tidal Jan 28 04:13:44 dolores volumio[17741]: info: explodeTIDALUri took 173 milliseconds Jan 28 04:13:44 dolores volumio[17741]: info: Exploding uri tidal://song/476664850 in service tidal Jan 28 04:13:44 dolores volumio[17741]: info: Exploding uri tidal://song/103844579 in service tidal Jan 28 04:13:44 dolores volumio[17741]: info: explodeTIDALUri took 222 milliseconds Jan 28 04:13:44 dolores volumio[17741]: info: Exploding uri tidal://song/186443687 in service tidal Jan 28 04:13:44 dolores volumio[17741]: info: explodeTIDALUri took 204 milliseconds Jan 28 04:13:45 dolores volumio[17741]: info: Exploding uri tidal://song/96692216 in service tidal Jan 28 04:13:45 dolores volumio[17741]: info: explodeTIDALUri took 206 milliseconds Jan 28 04:13:45 dolores volumio[17741]: info: Exploding uri tidal://song/186443690 in service tidal Jan 28 04:13:45 dolores volumio[17741]: info: Exploding uri tidal://song/171847098 in service tidal Jan 28 04:13:45 dolores volumio[17741]: info: Exploding uri tidal://song/476664855 in service tidal Jan 28 04:13:45 dolores volumio[17741]: info: explodeTIDALUri took 341 milliseconds Jan 28 04:13:45 dolores volumio[17741]: info: explodeTIDALUri took 273 milliseconds Jan 28 04:13:45 dolores volumio[17741]: info: Exploding uri tidal://song/193188801 in service tidal Jan 28 04:13:45 dolores volumio[17741]: info: explodeTIDALUri took 331 milliseconds Jan 28 04:13:45 dolores volumio[17741]: info: explodeTIDALUri took 234 milliseconds Jan 28 04:13:45 dolores volumio[17741]: info: Exploding uri tidal://song/154701781 in service tidal Jan 28 04:13:45 dolores volumio[17741]: info: explodeTIDALUri took 199 milliseconds Jan 28 04:13:45 dolores volumio[17741]: info: explodeTIDALUri took 193 milliseconds Jan 28 04:13:45 dolores volumio[17741]: info: Exploding uri tidal://song/91762101 in service tidal Jan 28 04:13:45 dolores volumio[17741]: info: Exploding uri tidal://song/91455449 in service tidal Jan 28 04:13:45 dolores volumio[17741]: info: explodeTIDALUri took 230 milliseconds Jan 28 04:13:45 dolores volumio[17741]: info: Exploding uri tidal://song/212679225 in service tidal Jan 28 04:13:45 dolores volumio[17741]: info: explodeTIDALUri took 186 milliseconds Jan 28 04:13:45 dolores volumio[17741]: info: Exploding uri tidal://song/476664853 in service tidal Jan 28 04:13:45 dolores volumio[17741]: info: explodeTIDALUri took 265 milliseconds Jan 28 04:13:45 dolores volumio[17741]: info: Exploding uri tidal://song/199662994 in service tidal Jan 28 04:13:45 dolores volumio[17741]: info: explodeTIDALUri took 208 milliseconds Jan 28 04:13:45 dolores volumio[17741]: info: Exploding uri tidal://song/476664854 in service tidal Jan 28 04:13:45 dolores volumio[17741]: info: explodeTIDALUri took 179 milliseconds Jan 28 04:13:45 dolores volumio[17741]: info: explodeTIDALUri took 253 milliseconds Jan 28 04:13:45 dolores volumio[17741]: info: Exploding uri tidal://song/171847093 in service tidal Jan 28 04:13:45 dolores volumio[17741]: info: Exploding uri tidal://song/197008269 in service tidal Jan 28 04:13:45 dolores volumio[17741]: info: explodeTIDALUri took 205 milliseconds Jan 28 04:13:45 dolores volumio[17741]: info: explodeTIDALUri took 192 milliseconds Jan 28 04:13:45 dolores volumio[17741]: info: Exploding uri tidal://song/37598504 in service tidal Jan 28 04:13:45 dolores volumio[17741]: info: Exploding uri tidal://song/154701784 in service tidal Jan 28 04:13:45 dolores volumio[17741]: info: explodeTIDALUri took 209 milliseconds Jan 28 04:13:45 dolores volumio[17741]: info: Exploding uri tidal://song/186443691 in service tidal Jan 28 04:13:45 dolores volumio[17741]: info: explodeTIDALUri took 225 milliseconds Jan 28 04:13:45 dolores volumio[17741]: info: Exploding uri tidal://song/212679226 in service tidal Jan 28 04:13:45 dolores volumio[17741]: info: explodeTIDALUri took 222 milliseconds Jan 28 04:13:45 dolores volumio[17741]: info: Exploding uri tidal://song/154701785 in service tidal Jan 28 04:13:45 dolores volumio[17741]: info: explodeTIDALUri took 217 milliseconds Jan 28 04:13:45 dolores volumio[17741]: info: Exploding uri tidal://song/176428312 in service tidal Jan 28 04:13:45 dolores volumio[17741]: info: explodeTIDALUri took 213 milliseconds Jan 28 04:13:46 dolores volumio[17741]: info: explodeTIDALUri took 266 milliseconds Jan 28 04:13:46 dolores volumio[17741]: info: explodeTIDALUri took 216 milliseconds Jan 28 04:13:46 dolores volumio[17741]: info: explodeTIDALUri took 166 milliseconds Jan 28 04:13:46 dolores volumio[17741]: info: explodeTIDALUri took 174 milliseconds Jan 28 04:13:46 dolores volumio[17741]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Saigon/6e3c00eb-8168-46f6-8bb8-16221d338255.jpg' Jan 28 04:13:46 dolores volumio[17741]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 28 04:13:46 dolores volumio[17741]: Error: connect ECONNREFUSED 158.69.209.125:443 Jan 28 04:13:46 dolores volumio[17741]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Jan 28 04:13:46 dolores volumio[17741]: errno: -111, Jan 28 04:13:46 dolores volumio[17741]: code: 'ECONNREFUSED', Jan 28 04:13:46 dolores volumio[17741]: syscall: 'connect', Jan 28 04:13:46 dolores volumio[17741]: address: '158.69.209.125', Jan 28 04:13:46 dolores volumio[17741]: port: 443 Jan 28 04:13:46 dolores volumio[17741]: } Jan 28 04:13:46 dolores volumio[17741]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 28 04:13:47 dolores sudo[6820]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-01-28 04:12 Jan 28 04:13:47 dolores sudo[6820]: 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="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="2f25e1a98e53e2dbc4f3878604e250b0379f3ea4" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="primo2rev2" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 07:52:21 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Primo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Primo" VOLUMIO_HASH="060c5b31331b90c840ee52059a8e3db0"