-- Logs begin at Wed 2025-05-07 17:08:22 CEST, end at Wed 2025-05-07 17:12:04 CEST. -- May 07 17:11:14 volumio-soalca volumio[882]: info: CoreCommandRouter::volumioPlay May 07 17:11:14 volumio-soalca volumio[882]: info: CoreStateMachine::play index undefined May 07 17:11:14 volumio-soalca volumio[882]: info: CoreStateMachine::setConsumeUpdateService undefined May 07 17:11:14 volumio-soalca volumio[882]: info: CorePlayQueue::getTrack 0 May 07 17:11:14 volumio-soalca volumio[882]: info: CoreStateMachine::startPlaybackTimer May 07 17:11:14 volumio-soalca volumio[882]: info: CorePlayQueue::getTrack 0 May 07 17:11:14 volumio-soalca volumio[882]: info: [1746630674321] ControllerWebradio::clearAddPlayTrack May 07 17:11:14 volumio-soalca volumio[882]: verbose: ControllerMpd::sendMpdCommand stop May 07 17:11:14 volumio-soalca volumio[882]: info: sendMpdCommand stop took 5 milliseconds May 07 17:11:14 volumio-soalca volumio[882]: verbose: ControllerMpd::sendMpdCommand clear May 07 17:11:14 volumio-soalca volumio[882]: info: sendMpdCommand clear took 1 milliseconds May 07 17:11:14 volumio-soalca volumio[882]: verbose: ControllerMpd::sendMpdCommand load "https://icstream.rds.radio/rdsrelax" May 07 17:11:14 volumio-soalca volumio[882]: info: May 07 17:11:14 volumio-soalca volumio[882]: ---------------------------- MPD announces system playlist update May 07 17:11:14 volumio-soalca volumio[882]: info: Ignoring MPD Status Update May 07 17:11:14 volumio-soalca volumio[882]: info: May 07 17:11:14 volumio-soalca volumio[882]: ---------------------------- MPD announces system playlist update May 07 17:11:14 volumio-soalca volumio[882]: info: Ignoring MPD Status Update May 07 17:11:14 volumio-soalca volumio[882]: info: May 07 17:11:14 volumio-soalca volumio[882]: ---------------------------- MPD announces system playlist update May 07 17:11:14 volumio-soalca volumio[882]: info: Ignoring MPD Status Update May 07 17:11:14 volumio-soalca volumio[882]: info: May 07 17:11:14 volumio-soalca volumio[882]: ---------------------------- MPD announces system playlist update May 07 17:11:14 volumio-soalca volumio[882]: info: Ignoring MPD Status Update May 07 17:11:14 volumio-soalca volumio[882]: error: Upnp client error: Error: This socket has been ended by the other party May 07 17:11:20 volumio-soalca volumio[882]: error: updateQueue error: null May 07 17:11:20 volumio-soalca volumio[882]: verbose: ControllerMpd::sendMpdCommand add "https://icstream.rds.radio/rdsrelax" May 07 17:11:20 volumio-soalca volumio[882]: info: ------------------------------ 6159ms May 07 17:11:20 volumio-soalca volumio[882]: info: ------------------------------ 6157ms May 07 17:11:20 volumio-soalca volumio[882]: info: ------------------------------ 6154ms May 07 17:11:20 volumio-soalca volumio[882]: info: ------------------------------ 6153ms May 07 17:11:20 volumio-soalca volumio[882]: Upnp client error: Error: This socket has been ended by the other party May 07 17:11:20 volumio-soalca volumio[882]: info: May 07 17:11:20 volumio-soalca volumio[882]: ---------------------------- MPD announces system playlist update May 07 17:11:20 volumio-soalca volumio[882]: info: Ignoring MPD Status Update May 07 17:11:20 volumio-soalca volumio[882]: info: sendMpdCommand add "https://icstream.rds.radio/rdsrelax" took 9 milliseconds May 07 17:11:20 volumio-soalca volumio[882]: info: CoreStateMachine::setConsumeUpdateService mpd May 07 17:11:20 volumio-soalca volumio[882]: verbose: ControllerMpd::sendMpdCommand play May 07 17:11:20 volumio-soalca volumio[882]: info: May 07 17:11:20 volumio-soalca volumio[882]: ---------------------------- MPD announces system playlist update May 07 17:11:20 volumio-soalca volumio[882]: info: Ignoring MPD Status Update May 07 17:11:20 volumio-soalca volumio[882]: info: May 07 17:11:20 volumio-soalca volumio[882]: ---------------------------- MPD announces system playlist update May 07 17:11:20 volumio-soalca volumio[882]: info: Ignoring MPD Status Update May 07 17:11:20 volumio-soalca volumio[882]: info: May 07 17:11:20 volumio-soalca volumio[882]: ---------------------------- MPD announces system playlist update May 07 17:11:20 volumio-soalca volumio[882]: info: Ignoring MPD Status Update May 07 17:11:20 volumio-soalca volumio[882]: info: ------------------------------ 16ms May 07 17:11:20 volumio-soalca volumio[882]: info: sendMpdCommand play took 13 milliseconds May 07 17:11:20 volumio-soalca volumio[882]: info: ------------------------------ 10ms May 07 17:11:20 volumio-soalca volumio[882]: info: ------------------------------ 8ms May 07 17:11:20 volumio-soalca volumio[882]: info: ------------------------------ 6ms May 07 17:11:20 volumio-soalca volumio[882]: info: May 07 17:11:20 volumio-soalca volumio[882]: ---------------------------- MPD announces state update: player May 07 17:11:20 volumio-soalca volumio[882]: info: ControllerMpd::getState May 07 17:11:20 volumio-soalca volumio[882]: verbose: ControllerMpd::sendMpdCommand status May 07 17:11:20 volumio-soalca volumio[882]: info: May 07 17:11:20 volumio-soalca volumio[882]: ---------------------------- MPD announces state update: player May 07 17:11:20 volumio-soalca volumio[882]: info: ControllerMpd::getState May 07 17:11:20 volumio-soalca volumio[882]: verbose: ControllerMpd::sendMpdCommand status May 07 17:11:20 volumio-soalca volumio[882]: info: May 07 17:11:20 volumio-soalca volumio[882]: ---------------------------- MPD announces state update: player May 07 17:11:20 volumio-soalca volumio[882]: info: ControllerMpd::getState May 07 17:11:20 volumio-soalca volumio[882]: verbose: ControllerMpd::sendMpdCommand status May 07 17:11:20 volumio-soalca volumio[882]: info: May 07 17:11:20 volumio-soalca volumio[882]: ---------------------------- MPD announces state update: player May 07 17:11:20 volumio-soalca volumio[882]: info: ControllerMpd::getState May 07 17:11:20 volumio-soalca volumio[882]: verbose: ControllerMpd::sendMpdCommand status May 07 17:11:20 volumio-soalca volumio[882]: info: FusionDsp - ---- read samplerate, raw: 48000,S32_LE,2,32 May 07 17:11:20 volumio-soalca volumio[882]: info: FusionDsp - ---- read samplerate from file: 48000 May 07 17:11:20 volumio-soalca volumio[882]: info: camilladsp stopping service pid 1287... May 07 17:11:20 volumio-soalca kernel: bcm2835_audio bcm2835_audio: failed to close VCHI service connection (status=-11) May 07 17:11:20 volumio-soalca volumio[882]: info: camilladsp service terminated, instance 1 May 07 17:11:20 volumio-soalca volumio[882]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 07 17:11:20 volumio-soalca volumio[882]: info: FusionDsp - Nulleq applied May 07 17:11:20 volumio-soalca volumio[882]: info: camilladsp service started and running in background, instance 1 May 07 17:11:20 volumio-soalca volumio[882]: info: May 07 17:11:20 volumio-soalca volumio[882]: ---------------------------- MPD announces state update: player May 07 17:11:20 volumio-soalca volumio[882]: info: May 07 17:11:20 volumio-soalca volumio[882]: ---------------------------- MPD announces system playlist update May 07 17:11:20 volumio-soalca volumio[882]: info: Ignoring MPD Status Update May 07 17:11:20 volumio-soalca volumio[882]: info: May 07 17:11:20 volumio-soalca volumio[882]: ---------------------------- MPD announces state update: player May 07 17:11:20 volumio-soalca volumio[882]: info: sendMpdCommand status took 58 milliseconds May 07 17:11:20 volumio-soalca volumio[882]: info: ControllerMpd::getState May 07 17:11:20 volumio-soalca volumio[882]: verbose: ControllerMpd::sendMpdCommand status May 07 17:11:20 volumio-soalca volumio[882]: info: sendMpdCommand status took 59 milliseconds May 07 17:11:20 volumio-soalca volumio[882]: info: sendMpdCommand status took 58 milliseconds May 07 17:11:20 volumio-soalca volumio[882]: info: sendMpdCommand status took 55 milliseconds May 07 17:11:20 volumio-soalca volumio[882]: info: ControllerMpd::getState May 07 17:11:20 volumio-soalca volumio[882]: verbose: ControllerMpd::sendMpdCommand status May 07 17:11:20 volumio-soalca volumio[882]: verbose: ControllerMpd::parseState May 07 17:11:20 volumio-soalca volumio[882]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 07 17:11:20 volumio-soalca volumio[882]: verbose: ControllerMpd::parseState May 07 17:11:20 volumio-soalca volumio[882]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 07 17:11:20 volumio-soalca volumio[882]: verbose: ControllerMpd::parseState May 07 17:11:20 volumio-soalca volumio[882]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 07 17:11:20 volumio-soalca volumio[882]: verbose: ControllerMpd::parseState May 07 17:11:20 volumio-soalca volumio[882]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 07 17:11:20 volumio-soalca volumio[882]: info: May 07 17:11:20 volumio-soalca volumio[882]: ---------------------------- MPD announces state update: player May 07 17:11:20 volumio-soalca volumio[882]: info: ControllerMpd::getState May 07 17:11:20 volumio-soalca volumio[882]: verbose: ControllerMpd::sendMpdCommand status May 07 17:11:20 volumio-soalca volumio[882]: info: May 07 17:11:20 volumio-soalca volumio[882]: ---------------------------- MPD announces state update: player May 07 17:11:20 volumio-soalca volumio[882]: info: ControllerMpd::getState May 07 17:11:20 volumio-soalca volumio[882]: verbose: ControllerMpd::sendMpdCommand status May 07 17:11:20 volumio-soalca volumio[882]: info: May 07 17:11:20 volumio-soalca volumio[882]: ---------------------------- MPD announces state update: player May 07 17:11:20 volumio-soalca volumio[882]: info: ControllerMpd::getState May 07 17:11:20 volumio-soalca volumio[882]: verbose: ControllerMpd::sendMpdCommand status May 07 17:11:20 volumio-soalca volumio[882]: info: sendMpdCommand status took 30 milliseconds May 07 17:11:20 volumio-soalca volumio[882]: info: ------------------------------ 40ms May 07 17:11:20 volumio-soalca volumio[882]: info: sendMpdCommand status took 30 milliseconds May 07 17:11:20 volumio-soalca volumio[882]: info: sendMpdCommand playlistinfo took 27 milliseconds May 07 17:11:20 volumio-soalca volumio[882]: info: sendMpdCommand playlistinfo took 24 milliseconds May 07 17:11:20 volumio-soalca volumio[882]: info: sendMpdCommand playlistinfo took 22 milliseconds May 07 17:11:20 volumio-soalca volumio[882]: info: sendMpdCommand playlistinfo took 21 milliseconds May 07 17:11:20 volumio-soalca volumio[882]: info: sendMpdCommand status took 16 milliseconds May 07 17:11:20 volumio-soalca volumio[882]: info: sendMpdCommand status took 13 milliseconds May 07 17:11:20 volumio-soalca volumio[882]: info: sendMpdCommand status took 9 milliseconds May 07 17:11:20 volumio-soalca volumio[882]: verbose: ControllerMpd::parseState May 07 17:11:20 volumio-soalca volumio[882]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 07 17:11:20 volumio-soalca volumio[882]: verbose: ControllerMpd::parseState May 07 17:11:20 volumio-soalca volumio[882]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 07 17:11:20 volumio-soalca volumio[882]: verbose: ControllerMpd::parseTrackInfo May 07 17:11:20 volumio-soalca volumio[882]: verbose: ControllerMpd::parseTrackInfo May 07 17:11:20 volumio-soalca volumio[882]: verbose: ControllerMpd::parseTrackInfo May 07 17:11:20 volumio-soalca volumio[882]: verbose: ControllerMpd::parseState May 07 17:11:20 volumio-soalca volumio[882]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 07 17:11:20 volumio-soalca volumio[882]: verbose: ControllerMpd::parseState May 07 17:11:20 volumio-soalca volumio[882]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 07 17:11:20 volumio-soalca volumio[882]: verbose: ControllerMpd::parseState May 07 17:11:20 volumio-soalca volumio[882]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 07 17:11:20 volumio-soalca volumio[882]: info: ControllerMpd::pushState May 07 17:11:20 volumio-soalca volumio[882]: info: CoreCommandRouter::servicePushState May 07 17:11:20 volumio-soalca volumio[882]: info: CorePlayQueue::getTrack 0 May 07 17:11:20 volumio-soalca volumio[882]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Song*L’ALBERO DELLE NOCI*BRUNORI SAS*2025*b6475ca5-e67a-49e3-acb5-975f416b4e02","artist":"RDS Relax","album":null,"uri":"https://icstream.rds.radio/rdsrelax","trackType":""} May 07 17:11:20 volumio-soalca volumio[882]: verbose: CURRENT POSITION 0 May 07 17:11:20 volumio-soalca volumio[882]: info: CoreStateMachine::syncState stateService play May 07 17:11:20 volumio-soalca volumio[882]: info: CoreStateMachine::syncState currentStatus stop May 07 17:11:20 volumio-soalca volumio[882]: info: ControllerMpd::pushState May 07 17:11:20 volumio-soalca volumio[882]: info: CoreCommandRouter::servicePushState May 07 17:11:20 volumio-soalca volumio[882]: info: CorePlayQueue::getTrack 0 May 07 17:11:20 volumio-soalca volumio[882]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Song*L’ALBERO DELLE NOCI*BRUNORI SAS*2025*b6475ca5-e67a-49e3-acb5-975f416b4e02","artist":"RDS Relax","album":null,"uri":"https://icstream.rds.radio/rdsrelax","trackType":""} May 07 17:11:20 volumio-soalca volumio[882]: verbose: CURRENT POSITION 0 May 07 17:11:20 volumio-soalca volumio[882]: info: CoreStateMachine::syncState stateService play May 07 17:11:20 volumio-soalca volumio[882]: info: CoreStateMachine::syncState currentStatus play May 07 17:11:20 volumio-soalca volumio[882]: info: Received an update from plugin. extracting info from payload May 07 17:11:20 volumio-soalca volumio[882]: info: CoreStateMachine::pushState May 07 17:11:20 volumio-soalca volumio[882]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 07 17:11:20 volumio-soalca volumio[882]: info: CoreCommandRouter::volumioPushState May 07 17:11:20 volumio-soalca volumio[882]: info: CoreStateMachine::pushState May 07 17:11:20 volumio-soalca volumio[882]: info: CoreCommandRouter::volumioPushState May 07 17:11:20 volumio-soalca volumio[882]: info: ControllerMpd::pushState May 07 17:11:20 volumio-soalca volumio[882]: info: CoreCommandRouter::servicePushState May 07 17:11:20 volumio-soalca volumio[882]: info: CorePlayQueue::getTrack 0 May 07 17:11:20 volumio-soalca volumio[882]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Song*L’ALBERO DELLE NOCI*BRUNORI SAS*2025*b6475ca5-e67a-49e3-acb5-975f416b4e02","artist":"RDS Relax","album":null,"uri":"https://icstream.rds.radio/rdsrelax","trackType":""} May 07 17:11:20 volumio-soalca volumio[882]: verbose: CURRENT POSITION 0 May 07 17:11:20 volumio-soalca volumio[882]: info: CoreStateMachine::syncState stateService play May 07 17:11:20 volumio-soalca volumio[882]: info: CoreStateMachine::syncState currentStatus play May 07 17:11:20 volumio-soalca volumio[882]: info: Received an update from plugin. extracting info from payload May 07 17:11:20 volumio-soalca volumio[882]: info: CoreStateMachine::pushState May 07 17:11:20 volumio-soalca volumio[882]: info: CoreCommandRouter::volumioPushState May 07 17:11:20 volumio-soalca volumio[882]: info: CoreStateMachine::pushState May 07 17:11:20 volumio-soalca volumio[882]: info: CoreCommandRouter::volumioPushState May 07 17:11:20 volumio-soalca volumio[882]: info: ControllerMpd::pushState May 07 17:11:20 volumio-soalca volumio[882]: info: CoreCommandRouter::servicePushState May 07 17:11:20 volumio-soalca volumio[882]: info: CorePlayQueue::getTrack 0 May 07 17:11:20 volumio-soalca volumio[882]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Song*L’ALBERO DELLE NOCI*BRUNORI SAS*2025*b6475ca5-e67a-49e3-acb5-975f416b4e02","artist":"RDS Relax","album":null,"uri":"https://icstream.rds.radio/rdsrelax","trackType":""} May 07 17:11:20 volumio-soalca volumio[882]: verbose: CURRENT POSITION 0 May 07 17:11:20 volumio-soalca volumio[882]: info: CoreStateMachine::syncState stateService play May 07 17:11:20 volumio-soalca volumio[882]: info: CoreStateMachine::syncState currentStatus play May 07 17:11:20 volumio-soalca volumio[882]: info: Received an update from plugin. extracting info from payload May 07 17:11:20 volumio-soalca volumio[882]: info: CoreStateMachine::pushState May 07 17:11:20 volumio-soalca volumio[882]: info: CoreCommandRouter::volumioPushState May 07 17:11:20 volumio-soalca volumio[882]: info: CoreStateMachine::pushState May 07 17:11:20 volumio-soalca volumio[882]: info: CoreCommandRouter::volumioPushState May 07 17:11:20 volumio-soalca volumio[882]: info: ------------------------------ 153ms May 07 17:11:20 volumio-soalca volumio[882]: info: ------------------------------ 200ms May 07 17:11:20 volumio-soalca volumio[882]: info: ------------------------------ 198ms May 07 17:11:20 volumio-soalca volumio[882]: info: ------------------------------ 197ms May 07 17:11:20 volumio-soalca volumio[882]: info: May 07 17:11:20 volumio-soalca volumio[882]: ---------------------------- MPD announces system playlist update May 07 17:11:20 volumio-soalca volumio[882]: info: Ignoring MPD Status Update May 07 17:11:20 volumio-soalca volumio[882]: info: May 07 17:11:20 volumio-soalca volumio[882]: ---------------------------- MPD announces state update: player May 07 17:11:20 volumio-soalca volumio[882]: info: ControllerMpd::getState May 07 17:11:20 volumio-soalca volumio[882]: verbose: ControllerMpd::sendMpdCommand status May 07 17:11:20 volumio-soalca volumio[882]: info: May 07 17:11:20 volumio-soalca volumio[882]: ---------------------------- MPD announces system playlist update May 07 17:11:20 volumio-soalca volumio[882]: info: Ignoring MPD Status Update May 07 17:11:20 volumio-soalca volumio[882]: info: May 07 17:11:20 volumio-soalca volumio[882]: ---------------------------- MPD announces state update: player May 07 17:11:20 volumio-soalca volumio[882]: info: ControllerMpd::getState May 07 17:11:20 volumio-soalca volumio[882]: verbose: ControllerMpd::sendMpdCommand status May 07 17:11:20 volumio-soalca volumio[882]: info: May 07 17:11:20 volumio-soalca volumio[882]: ---------------------------- MPD announces system playlist update May 07 17:11:20 volumio-soalca volumio[882]: info: Ignoring MPD Status Update May 07 17:11:20 volumio-soalca volumio[882]: info: May 07 17:11:20 volumio-soalca volumio[882]: ---------------------------- MPD announces state update: player May 07 17:11:20 volumio-soalca volumio[882]: info: ControllerMpd::getState May 07 17:11:20 volumio-soalca volumio[882]: verbose: ControllerMpd::sendMpdCommand status May 07 17:11:20 volumio-soalca volumio[882]: info: camilladsp spawned new process with pid 1578, instance 1, run: true May 07 17:11:20 volumio-soalca volumio[882]: info: sendMpdCommand playlistinfo took 144 milliseconds May 07 17:11:20 volumio-soalca volumio[882]: info: sendMpdCommand playlistinfo took 144 milliseconds May 07 17:11:20 volumio-soalca volumio[882]: info: sendMpdCommand playlistinfo took 140 milliseconds May 07 17:11:20 volumio-soalca volumio[882]: info: sendMpdCommand playlistinfo took 141 milliseconds May 07 17:11:20 volumio-soalca volumio[882]: info: sendMpdCommand playlistinfo took 141 milliseconds May 07 17:11:20 volumio-soalca volumio[882]: info: ------------------------------ 45ms May 07 17:11:20 volumio-soalca volumio[882]: info: sendMpdCommand status took 44 milliseconds May 07 17:11:20 volumio-soalca volumio[882]: info: ------------------------------ 44ms May 07 17:11:20 volumio-soalca volumio[882]: info: sendMpdCommand status took 41 milliseconds May 07 17:11:20 volumio-soalca volumio[882]: info: ------------------------------ 40ms May 07 17:11:20 volumio-soalca volumio[882]: info: sendMpdCommand status took 33 milliseconds May 07 17:11:20 volumio-soalca volumio[882]: verbose: ControllerMpd::parseTrackInfo May 07 17:11:20 volumio-soalca volumio[882]: verbose: ControllerMpd::parseTrackInfo May 07 17:11:20 volumio-soalca volumio[882]: verbose: ControllerMpd::parseTrackInfo May 07 17:11:20 volumio-soalca volumio[882]: verbose: ControllerMpd::parseTrackInfo May 07 17:11:20 volumio-soalca volumio[882]: verbose: ControllerMpd::parseTrackInfo May 07 17:11:20 volumio-soalca volumio[882]: verbose: ControllerMpd::parseState May 07 17:11:20 volumio-soalca volumio[882]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 07 17:11:20 volumio-soalca volumio[882]: verbose: ControllerMpd::parseState May 07 17:11:20 volumio-soalca volumio[882]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 07 17:11:20 volumio-soalca volumio[882]: verbose: ControllerMpd::parseState May 07 17:11:20 volumio-soalca volumio[882]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 07 17:11:20 volumio-soalca volumio[882]: info: ControllerMpd::pushState May 07 17:11:20 volumio-soalca volumio[882]: info: CoreCommandRouter::servicePushState May 07 17:11:20 volumio-soalca volumio[882]: info: CorePlayQueue::getTrack 0 May 07 17:11:20 volumio-soalca volumio[882]: verbose: STATE SERVICE {"status":"play","position":0,"seek":860,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Song*L’ALBERO DELLE NOCI*BRUNORI SAS*2025*b6475ca5-e67a-49e3-acb5-975f416b4e02","artist":"RDS Relax","album":null,"uri":"https://icstream.rds.radio/rdsrelax","trackType":""} May 07 17:11:20 volumio-soalca volumio[882]: verbose: CURRENT POSITION 0 May 07 17:11:20 volumio-soalca volumio[882]: info: CoreStateMachine::syncState stateService play May 07 17:11:20 volumio-soalca volumio[882]: info: CoreStateMachine::syncState currentStatus play May 07 17:11:20 volumio-soalca volumio[882]: info: Received an update from plugin. extracting info from payload May 07 17:11:20 volumio-soalca volumio[882]: info: CoreStateMachine::pushState May 07 17:11:20 volumio-soalca volumio[882]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 07 17:11:20 volumio-soalca volumio[882]: info: CoreCommandRouter::volumioPushState May 07 17:11:20 volumio-soalca volumio[882]: info: CoreStateMachine::pushState May 07 17:11:20 volumio-soalca volumio[882]: info: CoreCommandRouter::volumioPushState May 07 17:11:20 volumio-soalca volumio[882]: info: ControllerMpd::pushState May 07 17:11:20 volumio-soalca volumio[882]: info: CoreCommandRouter::servicePushState May 07 17:11:20 volumio-soalca volumio[882]: info: CorePlayQueue::getTrack 0 May 07 17:11:20 volumio-soalca volumio[882]: verbose: STATE SERVICE {"status":"play","position":0,"seek":860,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Song*L’ALBERO DELLE NOCI*BRUNORI SAS*2025*b6475ca5-e67a-49e3-acb5-975f416b4e02","artist":"RDS Relax","album":null,"uri":"https://icstream.rds.radio/rdsrelax","trackType":""} May 07 17:11:20 volumio-soalca volumio[882]: verbose: CURRENT POSITION 0 May 07 17:11:20 volumio-soalca volumio[882]: info: CoreStateMachine::syncState stateService play May 07 17:11:20 volumio-soalca volumio[882]: info: CoreStateMachine::syncState currentStatus play May 07 17:11:20 volumio-soalca volumio[882]: info: Received an update from plugin. extracting info from payload May 07 17:11:20 volumio-soalca volumio[882]: info: CoreStateMachine::pushState May 07 17:11:20 volumio-soalca volumio[882]: info: CoreCommandRouter::volumioPushState May 07 17:11:20 volumio-soalca volumio[882]: info: CoreStateMachine::pushState May 07 17:11:20 volumio-soalca volumio[882]: info: CoreCommandRouter::volumioPushState May 07 17:11:20 volumio-soalca volumio[882]: info: ControllerMpd::pushState May 07 17:11:20 volumio-soalca volumio[882]: info: CoreCommandRouter::servicePushState May 07 17:11:20 volumio-soalca volumio[882]: info: CorePlayQueue::getTrack 0 May 07 17:11:20 volumio-soalca volumio[882]: verbose: STATE SERVICE {"status":"play","position":0,"seek":860,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Song*L’ALBERO DELLE NOCI*BRUNORI SAS*2025*b6475ca5-e67a-49e3-acb5-975f416b4e02","artist":"RDS Relax","album":null,"uri":"https://icstream.rds.radio/rdsrelax","trackType":""} May 07 17:11:20 volumio-soalca volumio[882]: verbose: CURRENT POSITION 0 May 07 17:11:20 volumio-soalca volumio[882]: info: CoreStateMachine::syncState stateService play May 07 17:11:20 volumio-soalca volumio[882]: info: CoreStateMachine::syncState currentStatus play May 07 17:11:20 volumio-soalca volumio[882]: info: Received an update from plugin. extracting info from payload May 07 17:11:20 volumio-soalca volumio[882]: info: CoreStateMachine::pushState May 07 17:11:20 volumio-soalca volumio[882]: info: CoreCommandRouter::volumioPushState May 07 17:11:20 volumio-soalca volumio[882]: info: CoreStateMachine::pushState May 07 17:11:20 volumio-soalca volumio[882]: info: CoreCommandRouter::volumioPushState May 07 17:11:20 volumio-soalca volumio[882]: info: ControllerMpd::pushState May 07 17:11:20 volumio-soalca volumio[882]: info: CoreCommandRouter::servicePushState May 07 17:11:20 volumio-soalca volumio[882]: info: CorePlayQueue::getTrack 0 May 07 17:11:20 volumio-soalca volumio[882]: verbose: STATE SERVICE {"status":"play","position":0,"seek":860,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Song*L’ALBERO DELLE NOCI*BRUNORI SAS*2025*b6475ca5-e67a-49e3-acb5-975f416b4e02","artist":"RDS Relax","album":null,"uri":"https://icstream.rds.radio/rdsrelax","trackType":""} May 07 17:11:20 volumio-soalca volumio[882]: verbose: CURRENT POSITION 0 May 07 17:11:20 volumio-soalca volumio[882]: info: CoreStateMachine::syncState stateService play May 07 17:11:20 volumio-soalca volumio[882]: info: CoreStateMachine::syncState currentStatus play May 07 17:11:20 volumio-soalca volumio[882]: info: Received an update from plugin. extracting info from payload May 07 17:11:20 volumio-soalca volumio[882]: info: CoreStateMachine::pushState May 07 17:11:20 volumio-soalca volumio[882]: info: CoreCommandRouter::volumioPushState May 07 17:11:20 volumio-soalca volumio[882]: info: CoreStateMachine::pushState May 07 17:11:20 volumio-soalca volumio[882]: info: CoreCommandRouter::volumioPushState May 07 17:11:20 volumio-soalca volumio[882]: info: ControllerMpd::pushState May 07 17:11:20 volumio-soalca volumio[882]: info: CoreCommandRouter::servicePushState May 07 17:11:20 volumio-soalca volumio[882]: info: CorePlayQueue::getTrack 0 May 07 17:11:20 volumio-soalca volumio[882]: verbose: STATE SERVICE {"status":"play","position":0,"seek":860,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Song*L’ALBERO DELLE NOCI*BRUNORI SAS*2025*b6475ca5-e67a-49e3-acb5-975f416b4e02","artist":"RDS Relax","album":null,"uri":"https://icstream.rds.radio/rdsrelax","trackType":""} May 07 17:11:20 volumio-soalca volumio[882]: verbose: CURRENT POSITION 0 May 07 17:11:20 volumio-soalca volumio[882]: info: CoreStateMachine::syncState stateService play May 07 17:11:20 volumio-soalca volumio[882]: info: CoreStateMachine::syncState currentStatus play May 07 17:11:20 volumio-soalca volumio[882]: info: Received an update from plugin. extracting info from payload May 07 17:11:20 volumio-soalca volumio[882]: info: CoreStateMachine::pushState May 07 17:11:20 volumio-soalca volumio[882]: info: CoreCommandRouter::volumioPushState May 07 17:11:20 volumio-soalca volumio[882]: info: CoreStateMachine::pushState May 07 17:11:20 volumio-soalca volumio[882]: info: CoreCommandRouter::volumioPushState May 07 17:11:21 volumio-soalca volumio[882]: info: ------------------------------ 354ms May 07 17:11:21 volumio-soalca volumio[882]: info: ------------------------------ 352ms May 07 17:11:21 volumio-soalca volumio[882]: info: ------------------------------ 331ms May 07 17:11:21 volumio-soalca volumio[882]: info: ------------------------------ 329ms May 07 17:11:21 volumio-soalca volumio[882]: info: ------------------------------ 326ms May 07 17:11:21 volumio-soalca volumio[882]: info: [ASDebug] CurState: play PrevState: stop May 07 17:11:21 volumio-soalca volumio[882]: info: [ASDebug] Togle GPIO: ON May 07 17:11:21 volumio-soalca volumio[882]: info: [ASDebug] CurState: play PrevState: play May 07 17:11:21 volumio-soalca volumio[882]: info: [ASDebug] CurState: play PrevState: play May 07 17:11:21 volumio-soalca volumio[882]: info: [ASDebug] CurState: play PrevState: play May 07 17:11:21 volumio-soalca volumio[882]: info: [ASDebug] CurState: play PrevState: play May 07 17:11:21 volumio-soalca volumio[882]: info: [ASDebug] CurState: play PrevState: play May 07 17:11:21 volumio-soalca volumio[882]: info: [ASDebug] CurState: play PrevState: play May 07 17:11:21 volumio-soalca volumio[882]: info: [ASDebug] CurState: play PrevState: play May 07 17:11:21 volumio-soalca volumio[882]: info: [ASDebug] CurState: play PrevState: play May 07 17:11:21 volumio-soalca volumio[882]: info: [ASDebug] CurState: play PrevState: play May 07 17:11:21 volumio-soalca volumio[882]: info: [ASDebug] CurState: play PrevState: play May 07 17:11:21 volumio-soalca volumio[882]: info: [ASDebug] CurState: play PrevState: play May 07 17:11:21 volumio-soalca volumio[882]: info: [ASDebug] CurState: play PrevState: play May 07 17:11:21 volumio-soalca volumio[882]: info: [ASDebug] CurState: play PrevState: play May 07 17:11:21 volumio-soalca volumio[882]: info: [ASDebug] CurState: play PrevState: play May 07 17:11:21 volumio-soalca volumio[882]: info: [ASDebug] CurState: play PrevState: play May 07 17:11:21 volumio-soalca volumio[882]: info: [LastFM] Current track has sufficient metadata: title (Song*L’ALBERO DELLE NOCI*BRUNORI SAS*2025*b6475ca5-e67a-49e3-acb5-975f416b4e02) and artist (RDS Relax) passed on explicitly May 07 17:11:21 volumio-soalca volumio[882]: info: sendMpdCommand playlistinfo took 288 milliseconds May 07 17:11:21 volumio-soalca volumio[882]: info: sendMpdCommand playlistinfo took 289 milliseconds May 07 17:11:21 volumio-soalca volumio[882]: info: sendMpdCommand playlistinfo took 289 milliseconds May 07 17:11:21 volumio-soalca volumio[882]: verbose: ControllerMpd::parseTrackInfo May 07 17:11:21 volumio-soalca volumio[882]: verbose: ControllerMpd::parseTrackInfo May 07 17:11:21 volumio-soalca volumio[882]: verbose: ControllerMpd::parseTrackInfo May 07 17:11:21 volumio-soalca volumio[882]: info: ControllerMpd::pushState May 07 17:11:21 volumio-soalca volumio[882]: info: CoreCommandRouter::servicePushState May 07 17:11:21 volumio-soalca volumio[882]: info: CorePlayQueue::getTrack 0 May 07 17:11:21 volumio-soalca volumio[882]: verbose: STATE SERVICE {"status":"play","position":0,"seek":860,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Song*L’ALBERO DELLE NOCI*BRUNORI SAS*2025*b6475ca5-e67a-49e3-acb5-975f416b4e02","artist":"RDS Relax","album":null,"uri":"https://icstream.rds.radio/rdsrelax","trackType":""} May 07 17:11:21 volumio-soalca volumio[882]: verbose: CURRENT POSITION 0 May 07 17:11:21 volumio-soalca volumio[882]: info: CoreStateMachine::syncState stateService play May 07 17:11:21 volumio-soalca volumio[882]: info: CoreStateMachine::syncState currentStatus play May 07 17:11:21 volumio-soalca volumio[882]: info: Received an update from plugin. extracting info from payload May 07 17:11:21 volumio-soalca volumio[882]: info: CoreStateMachine::pushState May 07 17:11:21 volumio-soalca volumio[882]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 07 17:11:21 volumio-soalca volumio[882]: info: CoreCommandRouter::volumioPushState May 07 17:11:21 volumio-soalca volumio[882]: info: CoreStateMachine::pushState May 07 17:11:21 volumio-soalca volumio[882]: info: CoreCommandRouter::volumioPushState May 07 17:11:21 volumio-soalca volumio[882]: info: ControllerMpd::pushState May 07 17:11:21 volumio-soalca volumio[882]: info: CoreCommandRouter::servicePushState May 07 17:11:21 volumio-soalca volumio[882]: info: CorePlayQueue::getTrack 0 May 07 17:11:21 volumio-soalca volumio[882]: verbose: STATE SERVICE {"status":"play","position":0,"seek":860,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Song*L’ALBERO DELLE NOCI*BRUNORI SAS*2025*b6475ca5-e67a-49e3-acb5-975f416b4e02","artist":"RDS Relax","album":null,"uri":"https://icstream.rds.radio/rdsrelax","trackType":""} May 07 17:11:21 volumio-soalca volumio[882]: verbose: CURRENT POSITION 0 May 07 17:11:21 volumio-soalca volumio[882]: info: CoreStateMachine::syncState stateService play May 07 17:11:21 volumio-soalca volumio[882]: info: CoreStateMachine::syncState currentStatus play May 07 17:11:21 volumio-soalca volumio[882]: info: Received an update from plugin. extracting info from payload May 07 17:11:21 volumio-soalca volumio[882]: info: CoreStateMachine::pushState May 07 17:11:21 volumio-soalca volumio[882]: info: CoreCommandRouter::volumioPushState May 07 17:11:21 volumio-soalca volumio[882]: info: CoreStateMachine::pushState May 07 17:11:21 volumio-soalca volumio[882]: info: CoreCommandRouter::volumioPushState May 07 17:11:21 volumio-soalca volumio[882]: info: ControllerMpd::pushState May 07 17:11:21 volumio-soalca volumio[882]: info: CoreCommandRouter::servicePushState May 07 17:11:21 volumio-soalca volumio[882]: info: CorePlayQueue::getTrack 0 May 07 17:11:21 volumio-soalca volumio[882]: verbose: STATE SERVICE {"status":"play","position":0,"seek":860,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Song*L’ALBERO DELLE NOCI*BRUNORI SAS*2025*b6475ca5-e67a-49e3-acb5-975f416b4e02","artist":"RDS Relax","album":null,"uri":"https://icstream.rds.radio/rdsrelax","trackType":""} May 07 17:11:21 volumio-soalca volumio[882]: verbose: CURRENT POSITION 0 May 07 17:11:21 volumio-soalca volumio[882]: info: CoreStateMachine::syncState stateService play May 07 17:11:21 volumio-soalca volumio[882]: info: CoreStateMachine::syncState currentStatus play May 07 17:11:21 volumio-soalca volumio[882]: info: Received an update from plugin. extracting info from payload May 07 17:11:21 volumio-soalca volumio[882]: info: CoreStateMachine::pushState May 07 17:11:21 volumio-soalca volumio[882]: info: CoreCommandRouter::volumioPushState May 07 17:11:21 volumio-soalca volumio[882]: info: CoreStateMachine::pushState May 07 17:11:21 volumio-soalca volumio[882]: info: CoreCommandRouter::volumioPushState May 07 17:11:21 volumio-soalca volumio[882]: info: ------------------------------ 425ms May 07 17:11:21 volumio-soalca volumio[882]: info: ------------------------------ 423ms May 07 17:11:21 volumio-soalca volumio[882]: info: ------------------------------ 415ms May 07 17:11:21 volumio-soalca volumio[882]: info: [ASDebug] CurState: play PrevState: play May 07 17:11:21 volumio-soalca volumio[882]: info: [ASDebug] CurState: play PrevState: play May 07 17:11:21 volumio-soalca volumio[882]: info: [ASDebug] CurState: play PrevState: play May 07 17:11:21 volumio-soalca volumio[882]: info: [ASDebug] CurState: play PrevState: play May 07 17:11:21 volumio-soalca volumio[882]: info: [ASDebug] CurState: play PrevState: play May 07 17:11:21 volumio-soalca volumio[882]: info: [ASDebug] CurState: play PrevState: play May 07 17:11:34 volumio-soalca volumio[882]: info: [LastFM] track info: {"success":true,"trackInfo":{"name":"Song*L’ALBERO DELLE NOCI*BRUNORI SAS*2025*b6475ca5-e67a-49e3-acb5-975f416b4e02","url":"https://www.last.fm/music/RDS+Relax/_/Song%2AL&%238217;ALBERO+DELLE+NOCI%2ABRUNORI+SAS%2A2025%2Ab6475ca5-e67a-49e3-acb5-975f416b4e02","duration":"0","streamable":{"#":"0","@":{"fulltrack":"0"}},"listeners":"1","playcount":"18","artist":{"name":"RDS Relax","url":"https://www.last.fm/music/RDS+Relax"},"userplaycount":"19","userloved":"0","toptags":""}} May 07 17:11:34 volumio-soalca volumio[882]: info: [LastFM] Updated missing track duration: 0 May 07 17:11:35 volumio-soalca volumio[882]: info: May 07 17:11:35 volumio-soalca volumio[882]: ---------------------------- MPD announces system playlist update May 07 17:11:35 volumio-soalca volumio[882]: info: Ignoring MPD Status Update May 07 17:11:35 volumio-soalca volumio[882]: info: May 07 17:11:35 volumio-soalca volumio[882]: ---------------------------- MPD announces state update: player May 07 17:11:35 volumio-soalca volumio[882]: info: ControllerMpd::getState May 07 17:11:35 volumio-soalca volumio[882]: verbose: ControllerMpd::sendMpdCommand status May 07 17:11:35 volumio-soalca volumio[882]: info: May 07 17:11:35 volumio-soalca volumio[882]: ---------------------------- MPD announces system playlist update May 07 17:11:35 volumio-soalca volumio[882]: info: Ignoring MPD Status Update May 07 17:11:35 volumio-soalca volumio[882]: info: May 07 17:11:35 volumio-soalca volumio[882]: ---------------------------- MPD announces state update: player May 07 17:11:35 volumio-soalca volumio[882]: info: ControllerMpd::getState May 07 17:11:35 volumio-soalca volumio[882]: verbose: ControllerMpd::sendMpdCommand status May 07 17:11:35 volumio-soalca volumio[882]: info: May 07 17:11:35 volumio-soalca volumio[882]: ---------------------------- MPD announces system playlist update May 07 17:11:35 volumio-soalca volumio[882]: info: Ignoring MPD Status Update May 07 17:11:35 volumio-soalca volumio[882]: info: May 07 17:11:35 volumio-soalca volumio[882]: ---------------------------- MPD announces state update: player May 07 17:11:35 volumio-soalca volumio[882]: info: ControllerMpd::getState May 07 17:11:35 volumio-soalca volumio[882]: verbose: ControllerMpd::sendMpdCommand status May 07 17:11:35 volumio-soalca volumio[882]: info: May 07 17:11:35 volumio-soalca volumio[882]: ---------------------------- MPD announces system playlist update May 07 17:11:35 volumio-soalca volumio[882]: info: Ignoring MPD Status Update May 07 17:11:35 volumio-soalca volumio[882]: info: May 07 17:11:35 volumio-soalca volumio[882]: ---------------------------- MPD announces state update: player May 07 17:11:35 volumio-soalca volumio[882]: info: ControllerMpd::getState May 07 17:11:35 volumio-soalca volumio[882]: verbose: ControllerMpd::sendMpdCommand status May 07 17:11:35 volumio-soalca volumio[882]: info: ------------------------------ 13ms May 07 17:11:35 volumio-soalca volumio[882]: info: sendMpdCommand status took 11 milliseconds May 07 17:11:35 volumio-soalca volumio[882]: info: ------------------------------ 11ms May 07 17:11:35 volumio-soalca volumio[882]: info: sendMpdCommand status took 9 milliseconds May 07 17:11:35 volumio-soalca volumio[882]: info: ------------------------------ 9ms May 07 17:11:35 volumio-soalca volumio[882]: info: sendMpdCommand status took 8 milliseconds May 07 17:11:35 volumio-soalca volumio[882]: info: ------------------------------ 7ms May 07 17:11:35 volumio-soalca volumio[882]: info: sendMpdCommand status took 6 milliseconds May 07 17:11:35 volumio-soalca volumio[882]: verbose: ControllerMpd::parseState May 07 17:11:35 volumio-soalca volumio[882]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 07 17:11:35 volumio-soalca volumio[882]: verbose: ControllerMpd::parseState May 07 17:11:35 volumio-soalca volumio[882]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 07 17:11:35 volumio-soalca volumio[882]: verbose: ControllerMpd::parseState May 07 17:11:35 volumio-soalca volumio[882]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 07 17:11:35 volumio-soalca volumio[882]: verbose: ControllerMpd::parseState May 07 17:11:35 volumio-soalca volumio[882]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 07 17:11:35 volumio-soalca volumio[882]: info: sendMpdCommand playlistinfo took 4 milliseconds May 07 17:11:35 volumio-soalca volumio[882]: info: sendMpdCommand playlistinfo took 4 milliseconds May 07 17:11:35 volumio-soalca volumio[882]: info: sendMpdCommand playlistinfo took 4 milliseconds May 07 17:11:35 volumio-soalca volumio[882]: info: sendMpdCommand playlistinfo took 4 milliseconds May 07 17:11:35 volumio-soalca volumio[882]: verbose: ControllerMpd::parseTrackInfo May 07 17:11:35 volumio-soalca volumio[882]: verbose: ControllerMpd::parseTrackInfo May 07 17:11:35 volumio-soalca volumio[882]: verbose: ControllerMpd::parseTrackInfo May 07 17:11:35 volumio-soalca volumio[882]: verbose: ControllerMpd::parseTrackInfo May 07 17:11:35 volumio-soalca volumio[882]: info: ControllerMpd::pushState May 07 17:11:35 volumio-soalca volumio[882]: info: CoreCommandRouter::servicePushState May 07 17:11:35 volumio-soalca volumio[882]: info: CorePlayQueue::getTrack 0 May 07 17:11:35 volumio-soalca volumio[882]: verbose: STATE SERVICE {"status":"play","position":0,"seek":16359,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Song*OTHER SIDE OF THE WORLD*KT TUNSTALL*2005*90742128-27c1-4c73-8dd6-b31558f09ede","artist":"RDS Relax","album":null,"uri":"https://icstream.rds.radio/rdsrelax","trackType":""} May 07 17:11:35 volumio-soalca volumio[882]: verbose: CURRENT POSITION 0 May 07 17:11:35 volumio-soalca volumio[882]: info: CoreStateMachine::syncState stateService play May 07 17:11:35 volumio-soalca volumio[882]: info: CoreStateMachine::syncState currentStatus play May 07 17:11:35 volumio-soalca volumio[882]: info: Received an update from plugin. extracting info from payload May 07 17:11:35 volumio-soalca volumio[882]: info: CoreStateMachine::pushState May 07 17:11:35 volumio-soalca volumio[882]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 07 17:11:35 volumio-soalca volumio[882]: info: CoreCommandRouter::volumioPushState May 07 17:11:35 volumio-soalca volumio[882]: info: CoreStateMachine::pushState May 07 17:11:35 volumio-soalca volumio[882]: info: CoreCommandRouter::volumioPushState May 07 17:11:35 volumio-soalca volumio[882]: info: ControllerMpd::pushState May 07 17:11:35 volumio-soalca volumio[882]: info: CoreCommandRouter::servicePushState May 07 17:11:35 volumio-soalca volumio[882]: info: CorePlayQueue::getTrack 0 May 07 17:11:35 volumio-soalca volumio[882]: verbose: STATE SERVICE {"status":"play","position":0,"seek":16359,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Song*OTHER SIDE OF THE WORLD*KT TUNSTALL*2005*90742128-27c1-4c73-8dd6-b31558f09ede","artist":"RDS Relax","album":null,"uri":"https://icstream.rds.radio/rdsrelax","trackType":""} May 07 17:11:35 volumio-soalca volumio[882]: verbose: CURRENT POSITION 0 May 07 17:11:35 volumio-soalca volumio[882]: info: CoreStateMachine::syncState stateService play May 07 17:11:35 volumio-soalca volumio[882]: info: CoreStateMachine::syncState currentStatus play May 07 17:11:35 volumio-soalca volumio[882]: info: Received an update from plugin. extracting info from payload May 07 17:11:35 volumio-soalca volumio[882]: info: CoreStateMachine::pushState May 07 17:11:35 volumio-soalca volumio[882]: info: CoreCommandRouter::volumioPushState May 07 17:11:35 volumio-soalca volumio[882]: info: CoreStateMachine::pushState May 07 17:11:35 volumio-soalca volumio[882]: info: CoreCommandRouter::volumioPushState May 07 17:11:35 volumio-soalca volumio[882]: info: ControllerMpd::pushState May 07 17:11:35 volumio-soalca volumio[882]: info: CoreCommandRouter::servicePushState May 07 17:11:35 volumio-soalca volumio[882]: info: CorePlayQueue::getTrack 0 May 07 17:11:35 volumio-soalca volumio[882]: verbose: STATE SERVICE {"status":"play","position":0,"seek":16359,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Song*OTHER SIDE OF THE WORLD*KT TUNSTALL*2005*90742128-27c1-4c73-8dd6-b31558f09ede","artist":"RDS Relax","album":null,"uri":"https://icstream.rds.radio/rdsrelax","trackType":""} May 07 17:11:35 volumio-soalca volumio[882]: verbose: CURRENT POSITION 0 May 07 17:11:35 volumio-soalca volumio[882]: info: CoreStateMachine::syncState stateService play May 07 17:11:35 volumio-soalca volumio[882]: info: CoreStateMachine::syncState currentStatus play May 07 17:11:35 volumio-soalca volumio[882]: info: Received an update from plugin. extracting info from payload May 07 17:11:35 volumio-soalca volumio[882]: info: CoreStateMachine::pushState May 07 17:11:35 volumio-soalca volumio[882]: info: CoreCommandRouter::volumioPushState May 07 17:11:35 volumio-soalca volumio[882]: info: CoreStateMachine::pushState May 07 17:11:35 volumio-soalca volumio[882]: info: CoreCommandRouter::volumioPushState May 07 17:11:35 volumio-soalca volumio[882]: info: ControllerMpd::pushState May 07 17:11:35 volumio-soalca volumio[882]: info: CoreCommandRouter::servicePushState May 07 17:11:35 volumio-soalca volumio[882]: info: CorePlayQueue::getTrack 0 May 07 17:11:35 volumio-soalca volumio[882]: verbose: STATE SERVICE {"status":"play","position":0,"seek":16359,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Song*OTHER SIDE OF THE WORLD*KT TUNSTALL*2005*90742128-27c1-4c73-8dd6-b31558f09ede","artist":"RDS Relax","album":null,"uri":"https://icstream.rds.radio/rdsrelax","trackType":""} May 07 17:11:35 volumio-soalca volumio[882]: verbose: CURRENT POSITION 0 May 07 17:11:35 volumio-soalca volumio[882]: info: CoreStateMachine::syncState stateService play May 07 17:11:35 volumio-soalca volumio[882]: info: CoreStateMachine::syncState currentStatus play May 07 17:11:35 volumio-soalca volumio[882]: info: Received an update from plugin. extracting info from payload May 07 17:11:35 volumio-soalca volumio[882]: info: CoreStateMachine::pushState May 07 17:11:35 volumio-soalca volumio[882]: info: CoreCommandRouter::volumioPushState May 07 17:11:35 volumio-soalca volumio[882]: info: CoreStateMachine::pushState May 07 17:11:35 volumio-soalca volumio[882]: info: CoreCommandRouter::volumioPushState May 07 17:11:35 volumio-soalca volumio[882]: info: ------------------------------ 121ms May 07 17:11:35 volumio-soalca volumio[882]: info: ------------------------------ 119ms May 07 17:11:35 volumio-soalca volumio[882]: info: ------------------------------ 118ms May 07 17:11:35 volumio-soalca volumio[882]: info: ------------------------------ 116ms May 07 17:11:35 volumio-soalca volumio[882]: info: [ASDebug] CurState: play PrevState: play May 07 17:11:35 volumio-soalca volumio[882]: info: [ASDebug] CurState: play PrevState: play May 07 17:11:35 volumio-soalca volumio[882]: info: [ASDebug] CurState: play PrevState: play May 07 17:11:35 volumio-soalca volumio[882]: info: [ASDebug] CurState: play PrevState: play May 07 17:11:35 volumio-soalca volumio[882]: info: [ASDebug] CurState: play PrevState: play May 07 17:11:35 volumio-soalca volumio[882]: info: [ASDebug] CurState: play PrevState: play May 07 17:11:35 volumio-soalca volumio[882]: info: [ASDebug] CurState: play PrevState: play May 07 17:11:35 volumio-soalca volumio[882]: info: [ASDebug] CurState: play PrevState: play May 07 17:11:35 volumio-soalca volumio[882]: info: [LastFM] Current track has sufficient metadata: title (Song*OTHER SIDE OF THE WORLD*KT TUNSTALL*2005*90742128-27c1-4c73-8dd6-b31558f09ede) and artist (RDS Relax) passed on explicitly May 07 17:11:46 volumio-soalca volumiologrotate[544]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory May 07 17:11:46 volumio-soalca volumiologrotate[544]: ls: cannot access 'SOALCA': No such file or directory May 07 17:11:48 volumio-soalca volumio[882]: info: [LastFM] track info: {"success":true,"trackInfo":{"name":"Song*OTHER SIDE OF THE WORLD*KT TUNSTALL*2005*90742128-27c1-4c73-8dd6-b31558f09ede","url":"https://www.last.fm/music/RDS+Relax/_/Song%2AOTHER+SIDE+OF+THE+WORLD%2AKT+TUNSTALL%2A2005%2A90742128-27c1-4c73-8dd6-b31558f09ede","duration":"0","streamable":{"#":"0","@":{"fulltrack":"0"}},"listeners":"1","playcount":"1","artist":{"name":"RDS Relax","url":"https://www.last.fm/music/RDS+Relax"},"userplaycount":"1","userloved":"0","toptags":""}} May 07 17:11:48 volumio-soalca volumio[882]: info: [LastFM] Updated missing track duration: 0 May 07 17:12:03 volumio-soalca volumio[882]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 07 17:12:03 volumio-soalca volumio[882]: Error: getaddrinfo ENOTFOUND ws.audioscrobbler.com May 07 17:12:03 volumio-soalca volumio[882]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { May 07 17:12:03 volumio-soalca volumio[882]: errno: -3007, May 07 17:12:03 volumio-soalca volumio[882]: code: 'ENOTFOUND', May 07 17:12:03 volumio-soalca volumio[882]: syscall: 'getaddrinfo', May 07 17:12:03 volumio-soalca volumio[882]: hostname: 'ws.audioscrobbler.com' May 07 17:12:03 volumio-soalca volumio[882]: } May 07 17:12:03 volumio-soalca volumio[882]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 07 17:12:04 volumio-soalca sudo[1789]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-07 17:11 May 07 17:12:04 volumio-soalca sudo[1789]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Apr 2025 09:33:57 AM CEST" VOLUMIO_VERSION="3.804" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="354f95cf0a6c2cbcd57b1aca3179c81c"