-- Logs begin at Mon 2025-11-03 11:48:16 UTC, end at Mon 2025-11-03 17:34:25 UTC. -- Nov 03 17:33:03 volumio volumio[6675]: info: Nov 03 17:33:03 volumio volumio[6675]: ---------------------------- MPD announces system playlist update Nov 03 17:33:03 volumio volumio[6675]: info: Ignoring MPD Status Update Nov 03 17:33:03 volumio volumio[6675]: info: Nov 03 17:33:03 volumio volumio[6675]: ---------------------------- MPD announces state update: player Nov 03 17:33:03 volumio volumio[6675]: info: ControllerMpd::getState Nov 03 17:33:03 volumio volumio[6675]: verbose: ControllerMpd::sendMpdCommand status Nov 03 17:33:03 volumio volumio[6675]: info: Nov 03 17:33:03 volumio volumio[6675]: ---------------------------- MPD announces system playlist update Nov 03 17:33:03 volumio volumio[6675]: info: Ignoring MPD Status Update Nov 03 17:33:03 volumio volumio[6675]: info: Nov 03 17:33:03 volumio volumio[6675]: ---------------------------- MPD announces state update: player Nov 03 17:33:03 volumio volumio[6675]: info: ControllerMpd::getState Nov 03 17:33:03 volumio volumio[6675]: verbose: ControllerMpd::sendMpdCommand status Nov 03 17:33:03 volumio volumio[6675]: info: Nov 03 17:33:03 volumio volumio[6675]: ---------------------------- MPD announces system playlist update Nov 03 17:33:03 volumio volumio[6675]: info: Ignoring MPD Status Update Nov 03 17:33:03 volumio volumio[6675]: info: Nov 03 17:33:03 volumio volumio[6675]: ---------------------------- MPD announces state update: player Nov 03 17:33:03 volumio volumio[6675]: info: ControllerMpd::getState Nov 03 17:33:03 volumio volumio[6675]: verbose: ControllerMpd::sendMpdCommand status Nov 03 17:33:03 volumio volumio[6675]: info: Nov 03 17:33:03 volumio volumio[6675]: ---------------------------- MPD announces system playlist update Nov 03 17:33:03 volumio volumio[6675]: info: Ignoring MPD Status Update Nov 03 17:33:03 volumio volumio[6675]: info: Nov 03 17:33:03 volumio volumio[6675]: ---------------------------- MPD announces state update: player Nov 03 17:33:03 volumio volumio[6675]: info: ControllerMpd::getState Nov 03 17:33:03 volumio volumio[6675]: verbose: ControllerMpd::sendMpdCommand status Nov 03 17:33:03 volumio volumio[6675]: info: ------------------------------ 6ms Nov 03 17:33:03 volumio volumio[6675]: info: sendMpdCommand status took 4 milliseconds Nov 03 17:33:03 volumio volumio[6675]: info: ------------------------------ 5ms Nov 03 17:33:03 volumio volumio[6675]: info: sendMpdCommand status took 4 milliseconds Nov 03 17:33:03 volumio volumio[6675]: info: ------------------------------ 4ms Nov 03 17:33:03 volumio volumio[6675]: info: sendMpdCommand status took 3 milliseconds Nov 03 17:33:03 volumio volumio[6675]: info: ------------------------------ 2ms Nov 03 17:33:03 volumio volumio[6675]: info: sendMpdCommand status took 2 milliseconds Nov 03 17:33:03 volumio volumio[6675]: verbose: ControllerMpd::parseState Nov 03 17:33:03 volumio volumio[6675]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 17:33:03 volumio volumio[6675]: verbose: ControllerMpd::parseState Nov 03 17:33:03 volumio volumio[6675]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 17:33:03 volumio volumio[6675]: verbose: ControllerMpd::parseState Nov 03 17:33:03 volumio volumio[6675]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 17:33:03 volumio volumio[6675]: verbose: ControllerMpd::parseState Nov 03 17:33:03 volumio volumio[6675]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 17:33:03 volumio volumio[6675]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 03 17:33:03 volumio volumio[6675]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 03 17:33:03 volumio volumio[6675]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 03 17:33:03 volumio volumio[6675]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 03 17:33:03 volumio volumio[6675]: verbose: ControllerMpd::parseTrackInfo Nov 03 17:33:03 volumio volumio[6675]: verbose: ControllerMpd::parseTrackInfo Nov 03 17:33:03 volumio volumio[6675]: info: ControllerMpd::pushState Nov 03 17:33:03 volumio volumio[6675]: info: CoreCommandRouter::servicePushState Nov 03 17:33:03 volumio volumio[6675]: info: CorePlayQueue::getTrack 0 Nov 03 17:33:03 volumio volumio[6675]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2779257,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Joris Hessels","artist":"VRT Radio 1","album":null,"uri":"http://icecast.vrtcdn.be/radio1-high.mp3/tunein","trackType":"mp3/tunein"} Nov 03 17:33:03 volumio volumio[6675]: verbose: CURRENT POSITION 0 Nov 03 17:33:03 volumio volumio[6675]: info: CoreStateMachine::syncState stateService play Nov 03 17:33:03 volumio volumio[6675]: info: CoreStateMachine::syncState currentStatus play Nov 03 17:33:03 volumio volumio[6675]: info: Received an update from plugin. extracting info from payload Nov 03 17:33:03 volumio volumio[6675]: info: CoreStateMachine::pushState Nov 03 17:33:03 volumio volumio[6675]: info: CoreCommandRouter::volumioPushState Nov 03 17:33:03 volumio volumio[6675]: info: MRS: Pushing multiroomSync output update for this device Nov 03 17:33:03 volumio volumio[6675]: info: MRS: Pushing multiroomSync output Nov 03 17:33:03 volumio volumio[6675]: info: ControllerMpd::pushState Nov 03 17:33:03 volumio volumio[6675]: info: CoreCommandRouter::servicePushState Nov 03 17:33:03 volumio volumio[6675]: info: CorePlayQueue::getTrack 0 Nov 03 17:33:03 volumio volumio[6675]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2779257,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Joris Hessels","artist":"VRT Radio 1","album":null,"uri":"http://icecast.vrtcdn.be/radio1-high.mp3/tunein","trackType":"mp3/tunein"} Nov 03 17:33:03 volumio volumio[6675]: verbose: CURRENT POSITION 0 Nov 03 17:33:03 volumio volumio[6675]: info: CoreStateMachine::syncState stateService play Nov 03 17:33:03 volumio volumio[6675]: info: CoreStateMachine::syncState currentStatus play Nov 03 17:33:03 volumio volumio[6675]: info: Received an update from plugin. extracting info from payload Nov 03 17:33:03 volumio volumio[6675]: info: CoreStateMachine::pushState Nov 03 17:33:03 volumio volumio[6675]: info: CoreCommandRouter::volumioPushState Nov 03 17:33:03 volumio volumio[6675]: info: MRS: Pushing multiroomSync output update for this device Nov 03 17:33:03 volumio volumio[6675]: info: MRS: Pushing multiroomSync output Nov 03 17:33:03 volumio volumio[6675]: info: ControllerMpd::pushState Nov 03 17:33:03 volumio volumio[6675]: info: CoreCommandRouter::servicePushState Nov 03 17:33:03 volumio volumio[6675]: info: CorePlayQueue::getTrack 0 Nov 03 17:33:03 volumio volumio[6675]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2779257,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Joris Hessels","artist":"VRT Radio 1","album":null,"uri":"http://icecast.vrtcdn.be/radio1-high.mp3/tunein","trackType":"mp3/tunein"} Nov 03 17:33:03 volumio volumio[6675]: verbose: CURRENT POSITION 0 Nov 03 17:33:03 volumio volumio[6675]: info: CoreStateMachine::syncState stateService play Nov 03 17:33:03 volumio volumio[6675]: info: CoreStateMachine::syncState currentStatus play Nov 03 17:33:03 volumio volumio[6675]: info: Received an update from plugin. extracting info from payload Nov 03 17:33:03 volumio volumio[6675]: info: CoreStateMachine::pushState Nov 03 17:33:03 volumio volumio[6675]: info: CoreCommandRouter::volumioPushState Nov 03 17:33:03 volumio volumio[6675]: info: MRS: Pushing multiroomSync output update for this device Nov 03 17:33:03 volumio volumio[6675]: info: MRS: Pushing multiroomSync output Nov 03 17:33:03 volumio volumio[6675]: info: ControllerMpd::pushState Nov 03 17:33:03 volumio volumio[6675]: info: CoreCommandRouter::servicePushState Nov 03 17:33:03 volumio volumio[6675]: info: CorePlayQueue::getTrack 0 Nov 03 17:33:03 volumio volumio[6675]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2779257,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Joris Hessels","artist":"VRT Radio 1","album":null,"uri":"http://icecast.vrtcdn.be/radio1-high.mp3/tunein","trackType":"mp3/tunein"} Nov 03 17:33:03 volumio volumio[6675]: verbose: CURRENT POSITION 0 Nov 03 17:33:03 volumio volumio[6675]: info: CoreStateMachine::syncState stateService play Nov 03 17:33:03 volumio volumio[6675]: info: CoreStateMachine::syncState currentStatus play Nov 03 17:33:03 volumio volumio[6675]: info: Received an update from plugin. extracting info from payload Nov 03 17:33:03 volumio volumio[6675]: info: CoreStateMachine::pushState Nov 03 17:33:03 volumio volumio[6675]: info: CoreCommandRouter::volumioPushState Nov 03 17:33:03 volumio volumio[6675]: info: MRS: Pushing multiroomSync output update for this device Nov 03 17:33:03 volumio volumio[6675]: info: MRS: Pushing multiroomSync output Nov 03 17:33:03 volumio volumio[6675]: info: ------------------------------ 26ms Nov 03 17:33:03 volumio volumio[6675]: info: ------------------------------ 25ms Nov 03 17:33:03 volumio volumio[6675]: info: ------------------------------ 24ms Nov 03 17:33:03 volumio volumio[6675]: info: ------------------------------ 24ms Nov 03 17:33:03 volumio volumio[6675]: info: [LastFM] Current track has sufficient metadata: title (Joris Hessels) and artist (VRT Radio 1) passed on explicitly Nov 03 17:33:03 volumio volumio[6675]: info: FusionDsp - volume level for loudness 70 gain applied 0.00 Nov 03 17:33:03 volumio volumio[6675]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 03 17:33:03 volumio volumio[6675]: info: FusionDsp - Loudness is ON true Nov 03 17:33:03 volumio volumio[6675]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 03 17:33:03 volumio volumio[6675]: info: FusionDsp - volume level for loudness 70 gain applied 0.00 Nov 03 17:33:03 volumio volumio[6675]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 03 17:33:03 volumio volumio[6675]: info: FusionDsp - Loudness is ON true Nov 03 17:33:03 volumio volumio[6675]: info: FusionDsp - volume level for loudness 70 gain applied 0.00 Nov 03 17:33:03 volumio volumio[6675]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 03 17:33:03 volumio volumio[6675]: info: FusionDsp - Loudness is ON true Nov 03 17:33:03 volumio volumio[6675]: info: FusionDsp - volume level for loudness 70 gain applied 0.00 Nov 03 17:33:03 volumio volumio[6675]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 03 17:33:03 volumio volumio[6675]: info: FusionDsp - Loudness is ON true Nov 03 17:33:03 volumio volumio[6675]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 03 17:33:03 volumio volumio[6675]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 03 17:33:03 volumio volumio[6675]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 03 17:33:03 volumio volumio[6675]: error: [LastFM] track info request failed with error: Track not found Nov 03 17:33:23 volumio volumio[6675]: info: Nov 03 17:33:23 volumio volumio[6675]: ---------------------------- MPD announces system playlist update Nov 03 17:33:23 volumio volumio[6675]: info: Ignoring MPD Status Update Nov 03 17:33:23 volumio volumio[6675]: info: Nov 03 17:33:23 volumio volumio[6675]: ---------------------------- MPD announces state update: player Nov 03 17:33:23 volumio volumio[6675]: info: ControllerMpd::getState Nov 03 17:33:23 volumio volumio[6675]: verbose: ControllerMpd::sendMpdCommand status Nov 03 17:33:23 volumio volumio[6675]: info: Nov 03 17:33:23 volumio volumio[6675]: ---------------------------- MPD announces system playlist update Nov 03 17:33:23 volumio volumio[6675]: info: Ignoring MPD Status Update Nov 03 17:33:23 volumio volumio[6675]: info: Nov 03 17:33:23 volumio volumio[6675]: ---------------------------- MPD announces state update: player Nov 03 17:33:23 volumio volumio[6675]: info: ControllerMpd::getState Nov 03 17:33:23 volumio volumio[6675]: verbose: ControllerMpd::sendMpdCommand status Nov 03 17:33:23 volumio volumio[6675]: info: Nov 03 17:33:23 volumio volumio[6675]: ---------------------------- MPD announces system playlist update Nov 03 17:33:23 volumio volumio[6675]: info: Ignoring MPD Status Update Nov 03 17:33:23 volumio volumio[6675]: info: Nov 03 17:33:23 volumio volumio[6675]: ---------------------------- MPD announces state update: player Nov 03 17:33:23 volumio volumio[6675]: info: ControllerMpd::getState Nov 03 17:33:23 volumio volumio[6675]: verbose: ControllerMpd::sendMpdCommand status Nov 03 17:33:23 volumio volumio[6675]: info: ------------------------------ 4ms Nov 03 17:33:23 volumio volumio[6675]: info: sendMpdCommand status took 4 milliseconds Nov 03 17:33:23 volumio volumio[6675]: info: ------------------------------ 3ms Nov 03 17:33:23 volumio volumio[6675]: info: sendMpdCommand status took 3 milliseconds Nov 03 17:33:23 volumio volumio[6675]: verbose: ControllerMpd::parseState Nov 03 17:33:23 volumio volumio[6675]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 17:33:23 volumio volumio[6675]: verbose: ControllerMpd::parseState Nov 03 17:33:23 volumio volumio[6675]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 17:33:23 volumio volumio[6675]: info: Nov 03 17:33:23 volumio volumio[6675]: ---------------------------- MPD announces system playlist update Nov 03 17:33:23 volumio volumio[6675]: info: Ignoring MPD Status Update Nov 03 17:33:23 volumio volumio[6675]: info: Nov 03 17:33:23 volumio volumio[6675]: ---------------------------- MPD announces state update: player Nov 03 17:33:23 volumio volumio[6675]: info: ControllerMpd::getState Nov 03 17:33:23 volumio volumio[6675]: verbose: ControllerMpd::sendMpdCommand status Nov 03 17:33:23 volumio volumio[6675]: info: ------------------------------ 4ms Nov 03 17:33:23 volumio volumio[6675]: info: sendMpdCommand status took 3 milliseconds Nov 03 17:33:23 volumio volumio[6675]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 03 17:33:23 volumio volumio[6675]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 03 17:33:23 volumio volumio[6675]: info: ------------------------------ 2ms Nov 03 17:33:23 volumio volumio[6675]: info: sendMpdCommand status took 2 milliseconds Nov 03 17:33:23 volumio volumio[6675]: verbose: ControllerMpd::parseState Nov 03 17:33:23 volumio volumio[6675]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 17:33:23 volumio volumio[6675]: verbose: ControllerMpd::parseTrackInfo Nov 03 17:33:23 volumio volumio[6675]: verbose: ControllerMpd::parseState Nov 03 17:33:23 volumio volumio[6675]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 17:33:23 volumio volumio[6675]: info: ControllerMpd::pushState Nov 03 17:33:23 volumio volumio[6675]: info: CoreCommandRouter::servicePushState Nov 03 17:33:23 volumio volumio[6675]: info: CorePlayQueue::getTrack 0 Nov 03 17:33:23 volumio volumio[6675]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2799502,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Radio 1 - Alles begint bij luisteren","artist":"VRT Radio 1","album":null,"uri":"http://icecast.vrtcdn.be/radio1-high.mp3/tunein","trackType":"mp3/tunein"} Nov 03 17:33:23 volumio volumio[6675]: verbose: CURRENT POSITION 0 Nov 03 17:33:23 volumio volumio[6675]: info: CoreStateMachine::syncState stateService play Nov 03 17:33:23 volumio volumio[6675]: info: CoreStateMachine::syncState currentStatus play Nov 03 17:33:23 volumio volumio[6675]: info: Received an update from plugin. extracting info from payload Nov 03 17:33:23 volumio volumio[6675]: info: CoreStateMachine::pushState Nov 03 17:33:23 volumio volumio[6675]: info: CoreCommandRouter::volumioPushState Nov 03 17:33:23 volumio volumio[6675]: info: MRS: Pushing multiroomSync output update for this device Nov 03 17:33:23 volumio volumio[6675]: info: MRS: Pushing multiroomSync output Nov 03 17:33:23 volumio volumio[6675]: info: ControllerMpd::pushState Nov 03 17:33:23 volumio volumio[6675]: info: CoreCommandRouter::servicePushState Nov 03 17:33:23 volumio volumio[6675]: info: CorePlayQueue::getTrack 0 Nov 03 17:33:23 volumio volumio[6675]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2799502,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Radio 1 - Alles begint bij luisteren","artist":"VRT Radio 1","album":null,"uri":"http://icecast.vrtcdn.be/radio1-high.mp3/tunein","trackType":"mp3/tunein"} Nov 03 17:33:23 volumio volumio[6675]: verbose: CURRENT POSITION 0 Nov 03 17:33:23 volumio volumio[6675]: info: CoreStateMachine::syncState stateService play Nov 03 17:33:23 volumio volumio[6675]: info: CoreStateMachine::syncState currentStatus play Nov 03 17:33:23 volumio volumio[6675]: info: Received an update from plugin. extracting info from payload Nov 03 17:33:23 volumio volumio[6675]: info: CoreStateMachine::pushState Nov 03 17:33:23 volumio volumio[6675]: info: CoreCommandRouter::volumioPushState Nov 03 17:33:23 volumio volumio[6675]: info: MRS: Pushing multiroomSync output update for this device Nov 03 17:33:23 volumio volumio[6675]: info: MRS: Pushing multiroomSync output Nov 03 17:33:23 volumio volumio[6675]: info: ------------------------------ 26ms Nov 03 17:33:23 volumio volumio[6675]: info: ------------------------------ 26ms Nov 03 17:33:23 volumio volumio[6675]: info: sendMpdCommand playlistinfo took 21 milliseconds Nov 03 17:33:23 volumio volumio[6675]: info: sendMpdCommand playlistinfo took 21 milliseconds Nov 03 17:33:23 volumio volumio[6675]: verbose: ControllerMpd::parseTrackInfo Nov 03 17:33:23 volumio volumio[6675]: verbose: ControllerMpd::parseTrackInfo Nov 03 17:33:23 volumio volumio[6675]: info: ControllerMpd::pushState Nov 03 17:33:23 volumio volumio[6675]: info: CoreCommandRouter::servicePushState Nov 03 17:33:23 volumio volumio[6675]: info: CorePlayQueue::getTrack 0 Nov 03 17:33:23 volumio volumio[6675]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2799502,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Radio 1 - Alles begint bij luisteren","artist":"VRT Radio 1","album":null,"uri":"http://icecast.vrtcdn.be/radio1-high.mp3/tunein","trackType":"mp3/tunein"} Nov 03 17:33:23 volumio volumio[6675]: verbose: CURRENT POSITION 0 Nov 03 17:33:23 volumio volumio[6675]: info: CoreStateMachine::syncState stateService play Nov 03 17:33:23 volumio volumio[6675]: info: CoreStateMachine::syncState currentStatus play Nov 03 17:33:23 volumio volumio[6675]: info: Received an update from plugin. extracting info from payload Nov 03 17:33:23 volumio volumio[6675]: info: CoreStateMachine::pushState Nov 03 17:33:23 volumio volumio[6675]: info: CoreCommandRouter::volumioPushState Nov 03 17:33:23 volumio volumio[6675]: info: MRS: Pushing multiroomSync output update for this device Nov 03 17:33:23 volumio volumio[6675]: info: MRS: Pushing multiroomSync output Nov 03 17:33:23 volumio volumio[6675]: info: ControllerMpd::pushState Nov 03 17:33:23 volumio volumio[6675]: info: CoreCommandRouter::servicePushState Nov 03 17:33:23 volumio volumio[6675]: info: CorePlayQueue::getTrack 0 Nov 03 17:33:23 volumio volumio[6675]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2799502,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Radio 1 - Alles begint bij luisteren","artist":"VRT Radio 1","album":null,"uri":"http://icecast.vrtcdn.be/radio1-high.mp3/tunein","trackType":"mp3/tunein"} Nov 03 17:33:23 volumio volumio[6675]: verbose: CURRENT POSITION 0 Nov 03 17:33:23 volumio volumio[6675]: info: CoreStateMachine::syncState stateService play Nov 03 17:33:23 volumio volumio[6675]: info: CoreStateMachine::syncState currentStatus play Nov 03 17:33:23 volumio volumio[6675]: info: Received an update from plugin. extracting info from payload Nov 03 17:33:23 volumio volumio[6675]: info: CoreStateMachine::pushState Nov 03 17:33:23 volumio volumio[6675]: info: CoreCommandRouter::volumioPushState Nov 03 17:33:23 volumio volumio[6675]: info: MRS: Pushing multiroomSync output update for this device Nov 03 17:33:23 volumio volumio[6675]: info: MRS: Pushing multiroomSync output Nov 03 17:33:23 volumio volumio[6675]: info: ------------------------------ 38ms Nov 03 17:33:23 volumio volumio[6675]: info: ------------------------------ 35ms Nov 03 17:33:23 volumio volumio[6675]: info: [LastFM] Split composite title into artist: Radio 1 and title: Alles begint bij luisteren Nov 03 17:33:23 volumio volumio[6675]: info: FusionDsp - volume level for loudness 70 gain applied 0.00 Nov 03 17:33:23 volumio volumio[6675]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 03 17:33:23 volumio volumio[6675]: info: FusionDsp - Loudness is ON true Nov 03 17:33:23 volumio volumio[6675]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 03 17:33:23 volumio volumio[6675]: info: FusionDsp - volume level for loudness 70 gain applied 0.00 Nov 03 17:33:23 volumio volumio[6675]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 03 17:33:23 volumio volumio[6675]: info: FusionDsp - Loudness is ON true Nov 03 17:33:23 volumio volumio[6675]: info: FusionDsp - volume level for loudness 70 gain applied 0.00 Nov 03 17:33:23 volumio volumio[6675]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 03 17:33:23 volumio volumio[6675]: info: FusionDsp - Loudness is ON true Nov 03 17:33:23 volumio volumio[6675]: info: FusionDsp - volume level for loudness 70 gain applied 0.00 Nov 03 17:33:23 volumio volumio[6675]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 03 17:33:23 volumio volumio[6675]: info: FusionDsp - Loudness is ON true Nov 03 17:33:23 volumio volumio[6675]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 03 17:33:23 volumio volumio[6675]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 03 17:33:23 volumio volumio[6675]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 03 17:33:23 volumio volumio[6675]: info: [LastFM] track info: {"success":true,"trackInfo":{"name":"Alles begint bij luisteren","url":"https://www.last.fm/music/Radio+1/_/Alles+begint+bij+luisteren","duration":"0","streamable":{"#":"0","@":{"fulltrack":"0"}},"listeners":"43","playcount":"2868","artist":{"name":"Radio 1","url":"https://www.last.fm/music/Radio+1"},"userplaycount":"13","userloved":"0","toptags":""}} Nov 03 17:33:23 volumio volumio[6675]: info: [LastFM] Updated missing track duration: 0 Nov 03 17:33:44 volumio volumio[6675]: info: MRS: Found cast device: 2021/22-Philips-UHD--9c2bf8a918836635ca26117a013e20d9 Nov 03 17:33:44 volumio volumio[6675]: info: Adding audio output: Nov 03 17:33:46 volumio volumio[6675]: info: Nov 03 17:33:46 volumio volumio[6675]: ---------------------------- MPD announces system playlist update Nov 03 17:33:46 volumio volumio[6675]: info: Ignoring MPD Status Update Nov 03 17:33:46 volumio volumio[6675]: info: Nov 03 17:33:46 volumio volumio[6675]: ---------------------------- MPD announces state update: player Nov 03 17:33:46 volumio volumio[6675]: info: ControllerMpd::getState Nov 03 17:33:46 volumio volumio[6675]: verbose: ControllerMpd::sendMpdCommand status Nov 03 17:33:46 volumio volumio[6675]: info: Nov 03 17:33:46 volumio volumio[6675]: ---------------------------- MPD announces system playlist update Nov 03 17:33:46 volumio volumio[6675]: info: Ignoring MPD Status Update Nov 03 17:33:46 volumio volumio[6675]: info: Nov 03 17:33:46 volumio volumio[6675]: ---------------------------- MPD announces state update: player Nov 03 17:33:46 volumio volumio[6675]: info: ControllerMpd::getState Nov 03 17:33:46 volumio volumio[6675]: verbose: ControllerMpd::sendMpdCommand status Nov 03 17:33:46 volumio volumio[6675]: info: Nov 03 17:33:46 volumio volumio[6675]: ---------------------------- MPD announces system playlist update Nov 03 17:33:46 volumio volumio[6675]: info: Ignoring MPD Status Update Nov 03 17:33:46 volumio volumio[6675]: info: Nov 03 17:33:46 volumio volumio[6675]: ---------------------------- MPD announces state update: player Nov 03 17:33:46 volumio volumio[6675]: info: ControllerMpd::getState Nov 03 17:33:46 volumio volumio[6675]: verbose: ControllerMpd::sendMpdCommand status Nov 03 17:33:46 volumio volumio[6675]: info: ------------------------------ 7ms Nov 03 17:33:46 volumio volumio[6675]: info: sendMpdCommand status took 7 milliseconds Nov 03 17:33:46 volumio volumio[6675]: info: ------------------------------ 6ms Nov 03 17:33:46 volumio volumio[6675]: info: sendMpdCommand status took 3 milliseconds Nov 03 17:33:46 volumio volumio[6675]: verbose: ControllerMpd::parseState Nov 03 17:33:46 volumio volumio[6675]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 17:33:46 volumio volumio[6675]: verbose: ControllerMpd::parseState Nov 03 17:33:46 volumio volumio[6675]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 17:33:46 volumio volumio[6675]: info: Nov 03 17:33:46 volumio volumio[6675]: ---------------------------- MPD announces system playlist update Nov 03 17:33:46 volumio volumio[6675]: info: Ignoring MPD Status Update Nov 03 17:33:46 volumio volumio[6675]: info: Nov 03 17:33:46 volumio volumio[6675]: ---------------------------- MPD announces state update: player Nov 03 17:33:46 volumio volumio[6675]: info: ControllerMpd::getState Nov 03 17:33:46 volumio volumio[6675]: verbose: ControllerMpd::sendMpdCommand status Nov 03 17:33:46 volumio volumio[6675]: info: ------------------------------ 4ms Nov 03 17:33:46 volumio volumio[6675]: info: sendMpdCommand status took 4 milliseconds Nov 03 17:33:46 volumio volumio[6675]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 03 17:33:46 volumio volumio[6675]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 03 17:33:46 volumio volumio[6675]: info: ------------------------------ 2ms Nov 03 17:33:46 volumio volumio[6675]: info: sendMpdCommand status took 1 milliseconds Nov 03 17:33:46 volumio volumio[6675]: verbose: ControllerMpd::parseState Nov 03 17:33:46 volumio volumio[6675]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 17:33:46 volumio volumio[6675]: verbose: ControllerMpd::parseTrackInfo Nov 03 17:33:46 volumio volumio[6675]: verbose: ControllerMpd::parseTrackInfo Nov 03 17:33:46 volumio volumio[6675]: verbose: ControllerMpd::parseState Nov 03 17:33:46 volumio volumio[6675]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 17:33:46 volumio volumio[6675]: info: ControllerMpd::pushState Nov 03 17:33:46 volumio volumio[6675]: info: CoreCommandRouter::servicePushState Nov 03 17:33:46 volumio volumio[6675]: info: CorePlayQueue::getTrack 0 Nov 03 17:33:46 volumio volumio[6675]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2822004,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Voorproevers","artist":"VRT Radio 1","album":null,"uri":"http://icecast.vrtcdn.be/radio1-high.mp3/tunein","trackType":"mp3/tunein"} Nov 03 17:33:46 volumio volumio[6675]: verbose: CURRENT POSITION 0 Nov 03 17:33:46 volumio volumio[6675]: info: CoreStateMachine::syncState stateService play Nov 03 17:33:46 volumio volumio[6675]: info: CoreStateMachine::syncState currentStatus play Nov 03 17:33:46 volumio volumio[6675]: info: Received an update from plugin. extracting info from payload Nov 03 17:33:46 volumio volumio[6675]: info: CoreStateMachine::pushState Nov 03 17:33:46 volumio volumio[6675]: info: CoreCommandRouter::volumioPushState Nov 03 17:33:46 volumio volumio[6675]: info: MRS: Pushing multiroomSync output update for this device Nov 03 17:33:46 volumio volumio[6675]: info: MRS: Pushing multiroomSync output Nov 03 17:33:46 volumio volumio[6675]: info: ControllerMpd::pushState Nov 03 17:33:46 volumio volumio[6675]: info: CoreCommandRouter::servicePushState Nov 03 17:33:46 volumio volumio[6675]: info: CorePlayQueue::getTrack 0 Nov 03 17:33:46 volumio volumio[6675]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2822004,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Voorproevers","artist":"VRT Radio 1","album":null,"uri":"http://icecast.vrtcdn.be/radio1-high.mp3/tunein","trackType":"mp3/tunein"} Nov 03 17:33:46 volumio volumio[6675]: verbose: CURRENT POSITION 0 Nov 03 17:33:46 volumio volumio[6675]: info: CoreStateMachine::syncState stateService play Nov 03 17:33:46 volumio volumio[6675]: info: CoreStateMachine::syncState currentStatus play Nov 03 17:33:46 volumio volumio[6675]: info: Received an update from plugin. extracting info from payload Nov 03 17:33:46 volumio volumio[6675]: info: CoreStateMachine::pushState Nov 03 17:33:46 volumio volumio[6675]: info: CoreCommandRouter::volumioPushState Nov 03 17:33:46 volumio volumio[6675]: info: MRS: Pushing multiroomSync output update for this device Nov 03 17:33:46 volumio volumio[6675]: info: MRS: Pushing multiroomSync output Nov 03 17:33:46 volumio volumio[6675]: info: ------------------------------ 24ms Nov 03 17:33:46 volumio volumio[6675]: info: ------------------------------ 21ms Nov 03 17:33:46 volumio volumio[6675]: info: sendMpdCommand playlistinfo took 14 milliseconds Nov 03 17:33:46 volumio volumio[6675]: info: sendMpdCommand playlistinfo took 15 milliseconds Nov 03 17:33:46 volumio volumio[6675]: verbose: ControllerMpd::parseTrackInfo Nov 03 17:33:46 volumio volumio[6675]: verbose: ControllerMpd::parseTrackInfo Nov 03 17:33:46 volumio volumio[6675]: info: ControllerMpd::pushState Nov 03 17:33:46 volumio volumio[6675]: info: CoreCommandRouter::servicePushState Nov 03 17:33:46 volumio volumio[6675]: info: CorePlayQueue::getTrack 0 Nov 03 17:33:46 volumio volumio[6675]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2822004,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Voorproevers","artist":"VRT Radio 1","album":null,"uri":"http://icecast.vrtcdn.be/radio1-high.mp3/tunein","trackType":"mp3/tunein"} Nov 03 17:33:46 volumio volumio[6675]: verbose: CURRENT POSITION 0 Nov 03 17:33:46 volumio volumio[6675]: info: CoreStateMachine::syncState stateService play Nov 03 17:33:46 volumio volumio[6675]: info: CoreStateMachine::syncState currentStatus play Nov 03 17:33:46 volumio volumio[6675]: info: Received an update from plugin. extracting info from payload Nov 03 17:33:46 volumio volumio[6675]: info: CoreStateMachine::pushState Nov 03 17:33:46 volumio volumio[6675]: info: CoreCommandRouter::volumioPushState Nov 03 17:33:46 volumio volumio[6675]: info: MRS: Pushing multiroomSync output update for this device Nov 03 17:33:46 volumio volumio[6675]: info: MRS: Pushing multiroomSync output Nov 03 17:33:46 volumio volumio[6675]: info: ControllerMpd::pushState Nov 03 17:33:46 volumio volumio[6675]: info: CoreCommandRouter::servicePushState Nov 03 17:33:46 volumio volumio[6675]: info: CorePlayQueue::getTrack 0 Nov 03 17:33:46 volumio volumio[6675]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2822004,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Voorproevers","artist":"VRT Radio 1","album":null,"uri":"http://icecast.vrtcdn.be/radio1-high.mp3/tunein","trackType":"mp3/tunein"} Nov 03 17:33:46 volumio volumio[6675]: verbose: CURRENT POSITION 0 Nov 03 17:33:46 volumio volumio[6675]: info: CoreStateMachine::syncState stateService play Nov 03 17:33:46 volumio volumio[6675]: info: CoreStateMachine::syncState currentStatus play Nov 03 17:33:46 volumio volumio[6675]: info: Received an update from plugin. extracting info from payload Nov 03 17:33:46 volumio volumio[6675]: info: CoreStateMachine::pushState Nov 03 17:33:46 volumio volumio[6675]: info: CoreCommandRouter::volumioPushState Nov 03 17:33:46 volumio volumio[6675]: info: MRS: Pushing multiroomSync output update for this device Nov 03 17:33:46 volumio volumio[6675]: info: MRS: Pushing multiroomSync output Nov 03 17:33:46 volumio volumio[6675]: info: ------------------------------ 30ms Nov 03 17:33:46 volumio volumio[6675]: info: ------------------------------ 27ms Nov 03 17:33:46 volumio volumio[6675]: info: [LastFM] Current track has sufficient metadata: title (Voorproevers) and artist (VRT Radio 1) passed on explicitly Nov 03 17:33:46 volumio volumio[6675]: info: FusionDsp - volume level for loudness 70 gain applied 0.00 Nov 03 17:33:46 volumio volumio[6675]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 03 17:33:46 volumio volumio[6675]: info: FusionDsp - Loudness is ON true Nov 03 17:33:46 volumio volumio[6675]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 03 17:33:46 volumio volumio[6675]: info: FusionDsp - volume level for loudness 70 gain applied 0.00 Nov 03 17:33:46 volumio volumio[6675]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 03 17:33:46 volumio volumio[6675]: info: FusionDsp - Loudness is ON true Nov 03 17:33:46 volumio volumio[6675]: info: FusionDsp - volume level for loudness 70 gain applied 0.00 Nov 03 17:33:46 volumio volumio[6675]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 03 17:33:46 volumio volumio[6675]: info: FusionDsp - Loudness is ON true Nov 03 17:33:46 volumio volumio[6675]: info: FusionDsp - volume level for loudness 70 gain applied 0.00 Nov 03 17:33:46 volumio volumio[6675]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 03 17:33:46 volumio volumio[6675]: info: FusionDsp - Loudness is ON true Nov 03 17:33:46 volumio volumio[6675]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 03 17:33:46 volumio volumio[6675]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 03 17:33:46 volumio volumio[6675]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 03 17:33:46 volumio volumio[6675]: error: [LastFM] track info request failed with error: Track not found Nov 03 17:34:06 volumio volumio[6675]: info: Nov 03 17:34:06 volumio volumio[6675]: ---------------------------- MPD announces system playlist update Nov 03 17:34:06 volumio volumio[6675]: info: Ignoring MPD Status Update Nov 03 17:34:06 volumio volumio[6675]: info: Nov 03 17:34:06 volumio volumio[6675]: ---------------------------- MPD announces state update: player Nov 03 17:34:06 volumio volumio[6675]: info: ControllerMpd::getState Nov 03 17:34:06 volumio volumio[6675]: verbose: ControllerMpd::sendMpdCommand status Nov 03 17:34:06 volumio volumio[6675]: info: Nov 03 17:34:06 volumio volumio[6675]: ---------------------------- MPD announces system playlist update Nov 03 17:34:06 volumio volumio[6675]: info: Ignoring MPD Status Update Nov 03 17:34:06 volumio volumio[6675]: info: Nov 03 17:34:06 volumio volumio[6675]: ---------------------------- MPD announces state update: player Nov 03 17:34:06 volumio volumio[6675]: info: ControllerMpd::getState Nov 03 17:34:06 volumio volumio[6675]: verbose: ControllerMpd::sendMpdCommand status Nov 03 17:34:06 volumio volumio[6675]: info: Nov 03 17:34:06 volumio volumio[6675]: ---------------------------- MPD announces system playlist update Nov 03 17:34:06 volumio volumio[6675]: info: Ignoring MPD Status Update Nov 03 17:34:06 volumio volumio[6675]: info: Nov 03 17:34:06 volumio volumio[6675]: ---------------------------- MPD announces state update: player Nov 03 17:34:06 volumio volumio[6675]: info: ControllerMpd::getState Nov 03 17:34:06 volumio volumio[6675]: verbose: ControllerMpd::sendMpdCommand status Nov 03 17:34:06 volumio volumio[6675]: info: Nov 03 17:34:06 volumio volumio[6675]: ---------------------------- MPD announces system playlist update Nov 03 17:34:06 volumio volumio[6675]: info: Ignoring MPD Status Update Nov 03 17:34:06 volumio volumio[6675]: info: Nov 03 17:34:06 volumio volumio[6675]: ---------------------------- MPD announces state update: player Nov 03 17:34:06 volumio volumio[6675]: info: ControllerMpd::getState Nov 03 17:34:06 volumio volumio[6675]: verbose: ControllerMpd::sendMpdCommand status Nov 03 17:34:06 volumio volumio[6675]: info: ------------------------------ 6ms Nov 03 17:34:06 volumio volumio[6675]: info: sendMpdCommand status took 5 milliseconds Nov 03 17:34:06 volumio volumio[6675]: info: ------------------------------ 5ms Nov 03 17:34:06 volumio volumio[6675]: info: sendMpdCommand status took 4 milliseconds Nov 03 17:34:06 volumio volumio[6675]: info: ------------------------------ 4ms Nov 03 17:34:06 volumio volumio[6675]: info: sendMpdCommand status took 4 milliseconds Nov 03 17:34:06 volumio volumio[6675]: verbose: ControllerMpd::parseState Nov 03 17:34:06 volumio volumio[6675]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 17:34:06 volumio volumio[6675]: verbose: ControllerMpd::parseState Nov 03 17:34:06 volumio volumio[6675]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 17:34:06 volumio volumio[6675]: verbose: ControllerMpd::parseState Nov 03 17:34:06 volumio volumio[6675]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 17:34:06 volumio volumio[6675]: info: ------------------------------ 6ms Nov 03 17:34:06 volumio volumio[6675]: info: sendMpdCommand status took 5 milliseconds Nov 03 17:34:06 volumio volumio[6675]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 03 17:34:06 volumio volumio[6675]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 03 17:34:06 volumio volumio[6675]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 03 17:34:06 volumio volumio[6675]: verbose: ControllerMpd::parseState Nov 03 17:34:06 volumio volumio[6675]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 17:34:06 volumio volumio[6675]: verbose: ControllerMpd::parseTrackInfo Nov 03 17:34:06 volumio volumio[6675]: verbose: ControllerMpd::parseTrackInfo Nov 03 17:34:06 volumio volumio[6675]: verbose: ControllerMpd::parseTrackInfo Nov 03 17:34:06 volumio volumio[6675]: info: ControllerMpd::pushState Nov 03 17:34:06 volumio volumio[6675]: info: CoreCommandRouter::servicePushState Nov 03 17:34:06 volumio volumio[6675]: info: CorePlayQueue::getTrack 0 Nov 03 17:34:06 volumio volumio[6675]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2842124,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Joris Hessels","artist":"VRT Radio 1","album":null,"uri":"http://icecast.vrtcdn.be/radio1-high.mp3/tunein","trackType":"mp3/tunein"} Nov 03 17:34:06 volumio volumio[6675]: verbose: CURRENT POSITION 0 Nov 03 17:34:06 volumio volumio[6675]: info: CoreStateMachine::syncState stateService play Nov 03 17:34:06 volumio volumio[6675]: info: CoreStateMachine::syncState currentStatus play Nov 03 17:34:06 volumio volumio[6675]: info: Received an update from plugin. extracting info from payload Nov 03 17:34:06 volumio volumio[6675]: info: CoreStateMachine::pushState Nov 03 17:34:06 volumio volumio[6675]: info: CoreCommandRouter::volumioPushState Nov 03 17:34:06 volumio volumio[6675]: info: MRS: Pushing multiroomSync output update for this device Nov 03 17:34:06 volumio volumio[6675]: info: MRS: Pushing multiroomSync output Nov 03 17:34:06 volumio volumio[6675]: info: ControllerMpd::pushState Nov 03 17:34:06 volumio volumio[6675]: info: CoreCommandRouter::servicePushState Nov 03 17:34:06 volumio volumio[6675]: info: CorePlayQueue::getTrack 0 Nov 03 17:34:06 volumio volumio[6675]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2842124,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Joris Hessels","artist":"VRT Radio 1","album":null,"uri":"http://icecast.vrtcdn.be/radio1-high.mp3/tunein","trackType":"mp3/tunein"} Nov 03 17:34:06 volumio volumio[6675]: verbose: CURRENT POSITION 0 Nov 03 17:34:06 volumio volumio[6675]: info: CoreStateMachine::syncState stateService play Nov 03 17:34:06 volumio volumio[6675]: info: CoreStateMachine::syncState currentStatus play Nov 03 17:34:06 volumio volumio[6675]: info: Received an update from plugin. extracting info from payload Nov 03 17:34:06 volumio volumio[6675]: info: CoreStateMachine::pushState Nov 03 17:34:06 volumio volumio[6675]: info: CoreCommandRouter::volumioPushState Nov 03 17:34:06 volumio volumio[6675]: info: MRS: Pushing multiroomSync output update for this device Nov 03 17:34:06 volumio volumio[6675]: info: MRS: Pushing multiroomSync output Nov 03 17:34:06 volumio volumio[6675]: info: ControllerMpd::pushState Nov 03 17:34:06 volumio volumio[6675]: info: CoreCommandRouter::servicePushState Nov 03 17:34:06 volumio volumio[6675]: info: CorePlayQueue::getTrack 0 Nov 03 17:34:06 volumio volumio[6675]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2842124,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Joris Hessels","artist":"VRT Radio 1","album":null,"uri":"http://icecast.vrtcdn.be/radio1-high.mp3/tunein","trackType":"mp3/tunein"} Nov 03 17:34:06 volumio volumio[6675]: verbose: CURRENT POSITION 0 Nov 03 17:34:06 volumio volumio[6675]: info: CoreStateMachine::syncState stateService play Nov 03 17:34:06 volumio volumio[6675]: info: CoreStateMachine::syncState currentStatus play Nov 03 17:34:06 volumio volumio[6675]: info: Received an update from plugin. extracting info from payload Nov 03 17:34:06 volumio volumio[6675]: info: CoreStateMachine::pushState Nov 03 17:34:06 volumio volumio[6675]: info: CoreCommandRouter::volumioPushState Nov 03 17:34:06 volumio volumio[6675]: info: MRS: Pushing multiroomSync output update for this device Nov 03 17:34:06 volumio volumio[6675]: info: MRS: Pushing multiroomSync output Nov 03 17:34:06 volumio volumio[6675]: info: ------------------------------ 30ms Nov 03 17:34:06 volumio volumio[6675]: info: ------------------------------ 29ms Nov 03 17:34:06 volumio volumio[6675]: info: ------------------------------ 29ms Nov 03 17:34:06 volumio volumio[6675]: info: sendMpdCommand playlistinfo took 23 milliseconds Nov 03 17:34:06 volumio volumio[6675]: verbose: ControllerMpd::parseTrackInfo Nov 03 17:34:06 volumio volumio[6675]: info: ControllerMpd::pushState Nov 03 17:34:06 volumio volumio[6675]: info: CoreCommandRouter::servicePushState Nov 03 17:34:06 volumio volumio[6675]: info: CorePlayQueue::getTrack 0 Nov 03 17:34:06 volumio volumio[6675]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2842124,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Joris Hessels","artist":"VRT Radio 1","album":null,"uri":"http://icecast.vrtcdn.be/radio1-high.mp3/tunein","trackType":"mp3/tunein"} Nov 03 17:34:06 volumio volumio[6675]: verbose: CURRENT POSITION 0 Nov 03 17:34:06 volumio volumio[6675]: info: CoreStateMachine::syncState stateService play Nov 03 17:34:06 volumio volumio[6675]: info: CoreStateMachine::syncState currentStatus play Nov 03 17:34:06 volumio volumio[6675]: info: Received an update from plugin. extracting info from payload Nov 03 17:34:06 volumio volumio[6675]: info: CoreStateMachine::pushState Nov 03 17:34:06 volumio volumio[6675]: info: CoreCommandRouter::volumioPushState Nov 03 17:34:06 volumio volumio[6675]: info: MRS: Pushing multiroomSync output update for this device Nov 03 17:34:06 volumio volumio[6675]: info: MRS: Pushing multiroomSync output Nov 03 17:34:06 volumio volumio[6675]: info: ------------------------------ 34ms Nov 03 17:34:06 volumio volumio[6675]: info: [LastFM] Current track has sufficient metadata: title (Joris Hessels) and artist (VRT Radio 1) passed on explicitly Nov 03 17:34:06 volumio volumio[6675]: info: FusionDsp - volume level for loudness 70 gain applied 0.00 Nov 03 17:34:06 volumio volumio[6675]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 03 17:34:06 volumio volumio[6675]: info: FusionDsp - Loudness is ON true Nov 03 17:34:06 volumio volumio[6675]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 03 17:34:06 volumio volumio[6675]: info: FusionDsp - volume level for loudness 70 gain applied 0.00 Nov 03 17:34:06 volumio volumio[6675]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 03 17:34:06 volumio volumio[6675]: info: FusionDsp - Loudness is ON true Nov 03 17:34:06 volumio volumio[6675]: info: FusionDsp - volume level for loudness 70 gain applied 0.00 Nov 03 17:34:06 volumio volumio[6675]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 03 17:34:06 volumio volumio[6675]: info: FusionDsp - Loudness is ON true Nov 03 17:34:06 volumio volumio[6675]: info: FusionDsp - volume level for loudness 70 gain applied 0.00 Nov 03 17:34:06 volumio volumio[6675]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 03 17:34:06 volumio volumio[6675]: info: FusionDsp - Loudness is ON true Nov 03 17:34:06 volumio volumio[6675]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 03 17:34:06 volumio volumio[6675]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 03 17:34:06 volumio volumio[6675]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 03 17:34:06 volumio volumio[6675]: error: [LastFM] track info request failed with error: Track not found Nov 03 17:34:20 volumio volumio[6675]: info: CoreCommandRouter::volumioStop Nov 03 17:34:20 volumio volumio[6675]: info: CoreStateMachine::stop Nov 03 17:34:20 volumio volumio[6675]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 03 17:34:20 volumio volumio[6675]: info: CoreStateMachine::pushState Nov 03 17:34:20 volumio volumio[6675]: info: CorePlayQueue::getTrack 0 Nov 03 17:34:20 volumio volumio[6675]: info: CoreCommandRouter::volumioPushState Nov 03 17:34:20 volumio volumio[6675]: info: MRS: Pushing multiroomSync output update for this device Nov 03 17:34:20 volumio volumio[6675]: info: MRS: Pushing multiroomSync output Nov 03 17:34:20 volumio volumio[6675]: info: CoreStateMachine::stPlaybackTimer Nov 03 17:34:20 volumio volumio[6675]: info: CoreStateMachine::updateTrackBlock Nov 03 17:34:20 volumio volumio[6675]: info: CorePlayQueue::getTrackBlock Nov 03 17:34:20 volumio volumio[6675]: info: CoreStateMachine::serviceStop Nov 03 17:34:20 volumio volumio[6675]: info: CorePlayQueue::getTrack 0 Nov 03 17:34:20 volumio volumio[6675]: info: CoreCommandRouter::serviceStop Nov 03 17:34:20 volumio volumio[6675]: info: [1762191260917] ControllerWebradio::stop Nov 03 17:34:20 volumio volumio[6675]: verbose: ControllerMpd::sendMpdCommand stop Nov 03 17:34:20 volumio volumio[6675]: info: FusionDsp - volume level for loudness 70 gain applied 0.00 Nov 03 17:34:20 volumio volumio[6675]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 03 17:34:20 volumio volumio[6675]: info: FusionDsp - Loudness is ON true Nov 03 17:34:20 volumio volumio[6675]: info: touch_display: Setting screensaver timeout to 60 seconds. Nov 03 17:34:21 volumio volumio[6675]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 03 17:34:21 volumio volumio[6675]: info: Nov 03 17:34:21 volumio volumio[6675]: ---------------------------- MPD announces state update: player Nov 03 17:34:21 volumio volumio[6675]: info: sendMpdCommand stop took 111 milliseconds Nov 03 17:34:21 volumio volumio[6675]: info: ControllerMpd::getState Nov 03 17:34:21 volumio volumio[6675]: verbose: ControllerMpd::sendMpdCommand status Nov 03 17:34:21 volumio volumio[6675]: info: Nov 03 17:34:21 volumio volumio[6675]: ---------------------------- MPD announces state update: player Nov 03 17:34:21 volumio volumio[6675]: info: ControllerMpd::getState Nov 03 17:34:21 volumio volumio[6675]: verbose: ControllerMpd::sendMpdCommand status Nov 03 17:34:21 volumio volumio[6675]: info: Nov 03 17:34:21 volumio volumio[6675]: ---------------------------- MPD announces state update: player Nov 03 17:34:21 volumio volumio[6675]: info: ControllerMpd::getState Nov 03 17:34:21 volumio volumio[6675]: verbose: ControllerMpd::sendMpdCommand status Nov 03 17:34:21 volumio volumio[6675]: info: Nov 03 17:34:21 volumio volumio[6675]: ---------------------------- MPD announces state update: player Nov 03 17:34:21 volumio volumio[6675]: info: ControllerMpd::getState Nov 03 17:34:21 volumio volumio[6675]: verbose: ControllerMpd::sendMpdCommand status Nov 03 17:34:21 volumio volumio[6675]: info: sendMpdCommand status took 4 milliseconds Nov 03 17:34:21 volumio volumio[6675]: info: sendMpdCommand status took 9 milliseconds Nov 03 17:34:21 volumio volumio[6675]: info: sendMpdCommand status took 9 milliseconds Nov 03 17:34:21 volumio volumio[6675]: info: sendMpdCommand status took 8 milliseconds Nov 03 17:34:21 volumio volumio[6675]: verbose: ControllerMpd::parseState Nov 03 17:34:21 volumio volumio[6675]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 17:34:21 volumio volumio[6675]: verbose: ControllerMpd::parseState Nov 03 17:34:21 volumio volumio[6675]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 17:34:21 volumio volumio[6675]: verbose: ControllerMpd::parseState Nov 03 17:34:21 volumio volumio[6675]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 17:34:21 volumio volumio[6675]: verbose: ControllerMpd::parseState Nov 03 17:34:21 volumio volumio[6675]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 17:34:21 volumio volumio[6675]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 03 17:34:21 volumio volumio[6675]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 03 17:34:21 volumio volumio[6675]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 03 17:34:21 volumio volumio[6675]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 03 17:34:21 volumio volumio[6675]: verbose: ControllerMpd::parseTrackInfo Nov 03 17:34:21 volumio volumio[6675]: verbose: ControllerMpd::parseTrackInfo Nov 03 17:34:21 volumio volumio[6675]: verbose: ControllerMpd::parseTrackInfo Nov 03 17:34:21 volumio volumio[6675]: verbose: ControllerMpd::parseTrackInfo Nov 03 17:34:21 volumio volumio[6675]: info: ControllerMpd::pushState Nov 03 17:34:21 volumio volumio[6675]: info: CoreCommandRouter::servicePushState Nov 03 17:34:21 volumio volumio[6675]: info: CoreStateMachine::pushState Nov 03 17:34:21 volumio volumio[6675]: info: CorePlayQueue::getTrack 0 Nov 03 17:34:21 volumio volumio[6675]: info: CoreCommandRouter::volumioPushState Nov 03 17:34:21 volumio volumio[6675]: info: MRS: Pushing multiroomSync output update for this device Nov 03 17:34:21 volumio volumio[6675]: info: MRS: Pushing multiroomSync output Nov 03 17:34:21 volumio volumio[6675]: info: CorePlayQueue::getTrack 0 Nov 03 17:34:21 volumio volumio[6675]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 03 17:34:21 volumio volumio[6675]: info: ControllerMpd::pushState Nov 03 17:34:21 volumio volumio[6675]: info: CoreCommandRouter::servicePushState Nov 03 17:34:21 volumio volumio[6675]: info: CoreStateMachine::pushState Nov 03 17:34:21 volumio volumio[6675]: info: CorePlayQueue::getTrack 0 Nov 03 17:34:21 volumio volumio[6675]: info: CoreCommandRouter::volumioPushState Nov 03 17:34:21 volumio volumio[6675]: info: MRS: Pushing multiroomSync output update for this device Nov 03 17:34:21 volumio volumio[6675]: info: MRS: Pushing multiroomSync output Nov 03 17:34:21 volumio volumio[6675]: info: CorePlayQueue::getTrack 0 Nov 03 17:34:21 volumio volumio[6675]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 03 17:34:21 volumio volumio[6675]: info: ControllerMpd::pushState Nov 03 17:34:21 volumio volumio[6675]: info: CoreCommandRouter::servicePushState Nov 03 17:34:21 volumio volumio[6675]: info: CoreStateMachine::pushState Nov 03 17:34:21 volumio volumio[6675]: info: CorePlayQueue::getTrack 0 Nov 03 17:34:21 volumio volumio[6675]: info: CoreCommandRouter::volumioPushState Nov 03 17:34:21 volumio volumio[6675]: info: MRS: Pushing multiroomSync output update for this device Nov 03 17:34:21 volumio volumio[6675]: info: MRS: Pushing multiroomSync output Nov 03 17:34:21 volumio volumio[6675]: info: CorePlayQueue::getTrack 0 Nov 03 17:34:21 volumio volumio[6675]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 03 17:34:21 volumio volumio[6675]: info: ControllerMpd::pushState Nov 03 17:34:21 volumio volumio[6675]: info: CoreCommandRouter::servicePushState Nov 03 17:34:21 volumio volumio[6675]: info: CoreStateMachine::pushState Nov 03 17:34:21 volumio volumio[6675]: info: CorePlayQueue::getTrack 0 Nov 03 17:34:21 volumio volumio[6675]: info: CoreCommandRouter::volumioPushState Nov 03 17:34:21 volumio volumio[6675]: info: MRS: Pushing multiroomSync output update for this device Nov 03 17:34:21 volumio volumio[6675]: info: MRS: Pushing multiroomSync output Nov 03 17:34:21 volumio volumio[6675]: info: CorePlayQueue::getTrack 0 Nov 03 17:34:21 volumio volumio[6675]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 03 17:34:21 volumio volumio[6675]: info: ------------------------------ 46ms Nov 03 17:34:21 volumio volumio[6675]: info: ------------------------------ 44ms Nov 03 17:34:21 volumio volumio[6675]: info: ------------------------------ 44ms Nov 03 17:34:21 volumio volumio[6675]: info: ------------------------------ 44ms Nov 03 17:34:21 volumio volumio[6675]: info: FusionDsp - volume level for loudness 70 gain applied 0.00 Nov 03 17:34:21 volumio volumio[6675]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 03 17:34:21 volumio volumio[6675]: info: FusionDsp - Loudness is ON true Nov 03 17:34:21 volumio volumio[6675]: info: FusionDsp - volume level for loudness 70 gain applied 0.00 Nov 03 17:34:21 volumio volumio[6675]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 03 17:34:21 volumio volumio[6675]: info: FusionDsp - Loudness is ON true Nov 03 17:34:21 volumio volumio[6675]: info: FusionDsp - volume level for loudness 70 gain applied 0.00 Nov 03 17:34:21 volumio volumio[6675]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 03 17:34:21 volumio volumio[6675]: info: FusionDsp - Loudness is ON true Nov 03 17:34:21 volumio volumio[6675]: info: FusionDsp - volume level for loudness 70 gain applied 0.00 Nov 03 17:34:21 volumio volumio[6675]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 03 17:34:21 volumio volumio[6675]: info: FusionDsp - Loudness is ON true Nov 03 17:34:21 volumio volumio[6675]: info: CoreCommandRouter::volumioGetState Nov 03 17:34:21 volumio volumio[6675]: info: CorePlayQueue::getTrack 0 Nov 03 17:34:21 volumio volumio[6675]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Nov 03 17:34:21 volumio volumio[6675]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 03 17:34:21 volumio volumio[6675]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 03 17:34:21 volumio volumio[6675]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 03 17:34:21 volumio volumio[6675]: info: touch_display: Setting screensaver timeout to 60 seconds. Nov 03 17:34:21 volumio volumio[6675]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 03 17:34:23 volumio volumio[6675]: info: ___________ PLUGINS: Run Shutdown Tasks ___________ Nov 03 17:34:23 volumio volumio[6675]: info: PLUGIN onShutdown : networkfs Nov 03 17:34:23 volumio sudo[7927]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /mnt/NAS/NAS834A2E Nov 03 17:34:23 volumio sudo[7927]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 17:34:23 volumio systemd[1]: mnt-NAS-NAS834A2E.mount: Succeeded. Nov 03 17:34:23 volumio sudo[7927]: pam_unix(sudo:session): session closed for user root Nov 03 17:34:23 volumio volumio[6675]: info: PLUGIN onShutdown : audiophonicsonoff Nov 03 17:34:23 volumio volumio[6675]: info: PLUGIN onShutdown : touch_display Nov 03 17:34:23 volumio volumio[6675]: info: Executing endpoint metavolumio Nov 03 17:34:23 volumio volumio[6675]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 03 17:34:23 volumio sudo[7932]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sed -i -e /Option "TransformationMatrix"/d /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Nov 03 17:34:23 volumio sudo[7932]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 17:34:23 volumio sudo[7932]: pam_unix(sudo:session): session closed for user root Nov 03 17:34:23 volumio volumio[6675]: info: touch_display: Touchscreen transformation matrix removed. Nov 03 17:34:24 volumio sudo[7940]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl poweroff Nov 03 17:34:24 volumio sudo[7940]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 17:34:24 volumio systemd-logind[490]: System is powering down. Nov 03 17:34:24 volumio sudo[7940]: pam_unix(sudo:session): session closed for user root Nov 03 17:34:24 volumio systemd[1]: Stopping Volumio Tidal Connect Service... Nov 03 17:34:24 volumio autossh[7063]: received signal to exit (15) Nov 03 17:34:24 volumio systemd[1]: Stopping MyVolumio SSH Tunnel... Nov 03 17:34:24 volumio systemd[1]: Unmounting RPC Pipe File System... Nov 03 17:34:24 volumio systemd[1]: systemd-rfkill.socket: Succeeded. Nov 03 17:34:24 volumio systemd[1]: Closed Load/Save RF Kill Switch Status /dev/rfkill Watch. Nov 03 17:34:24 volumio systemd[1]: Stopped target Bluetooth. Nov 03 17:34:24 volumio systemd[1]: Stopping FusionDsp Daemon... Nov 03 17:34:24 volumio systemd[1]: Stopping Authorization Manager... Nov 03 17:34:24 volumio systemd[1]: Stopping RealtimeKit Scheduling Policy Service... Nov 03 17:34:24 volumio systemd[1]: Stopped target Graphical Interface. Nov 03 17:34:24 volumio volumio[1079]: Backend configuration: Nov 03 17:34:24 volumio volumio[1079]: active_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml Nov 03 17:34:24 volumio volumio[1079]: active_config_txt: null Nov 03 17:34:24 volumio volumio[1079]: camilla_host: 127.0.0.1 Nov 03 17:34:24 volumio volumio[1079]: camilla_port: 9876 Nov 03 17:34:24 volumio volumio[1079]: coeff_dir: /data/INTERNAL/FusionDsp/filters Nov 03 17:34:24 volumio volumio[1079]: config_dir: /data/configuration/audio_interface/fusiondsp Nov 03 17:34:24 volumio volumio[1079]: default_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml Nov 03 17:34:24 volumio volumio[1079]: log_file: /tmp/camilladsp.log Nov 03 17:34:24 volumio volumio[1079]: on_get_active_config: null Nov 03 17:34:24 volumio volumio[1079]: on_set_active_config: null Nov 03 17:34:24 volumio volumio[1079]: port: 5011 Nov 03 17:34:24 volumio volumio[1079]: supported_capture_types: null Nov 03 17:34:24 volumio volumio[1079]: supported_playback_types: null Nov 03 17:34:24 volumio volumio[1079]: update_config_symlink: false Nov 03 17:34:24 volumio volumio[1079]: update_config_txt: false Nov 03 17:34:24 volumio volumio[1079]: ======== Running on http://0.0.0.0:5011 ======== Nov 03 17:34:24 volumio volumio[1079]: (Press CTRL+C to quit) Nov 03 17:34:24 volumio systemd[1]: Stopped target Timers. Nov 03 17:34:24 volumio systemd[1]: apt-daily-upgrade.timer: Succeeded. Nov 03 17:34:24 volumio systemd[1]: Stopped Daily apt upgrade and clean activities. Nov 03 17:34:24 volumio systemd[1]: Starting Turns off Raspberry Pi display backlight on shutdown/reboot... Nov 03 17:34:24 volumio systemd[1]: man-db.timer: Succeeded. Nov 03 17:34:24 volumio systemd[1]: Stopped Daily man-db regeneration. Nov 03 17:34:24 volumio bluetoothd[719]: Terminating Nov 03 17:34:24 volumio systemd[1]: Stopping Bluetooth service... Nov 03 17:34:24 volumio systemd[1]: Stopping Volumio Bluetooth Module... Nov 03 17:34:24 volumio systemd[1]: apt-daily.timer: Succeeded. Nov 03 17:34:24 volumio bluetoothd[719]: Endpoint unregistered: sender=:1.57 path=/MediaEndpoint/A2DPSource Nov 03 17:34:24 volumio bluetoothd[719]: Endpoint unregistered: sender=:1.57 path=/MediaEndpoint/A2DPSink Nov 03 17:34:24 volumio systemd[1]: Stopped Daily apt download activities. Nov 03 17:34:24 volumio systemd[1]: Stopped target System Time Synchronized. Nov 03 17:34:24 volumio systemd[1]: Stopped target Multi-User System. Nov 03 17:34:24 volumio ntpd[797]: ntpd exiting on signal 15 (Terminated) Nov 03 17:34:24 volumio ntpd[797]: 81.240.251.103 local addr 192.168.129.30 -> Nov 03 17:34:24 volumio ntpd[797]: 81.244.255.72 local addr 192.168.129.30 -> Nov 03 17:34:24 volumio ntpd[797]: 81.240.251.106 local addr 192.168.129.30 -> Nov 03 17:34:24 volumio ntpd[797]: 81.244.255.86 local addr 192.168.129.30 -> Nov 03 17:34:24 volumio ntpd[797]: 193.121.15.225 local addr 192.168.129.30 -> Nov 03 17:34:24 volumio volumiobt[7946]: Killing pulse Nov 03 17:34:24 volumio ntpd[797]: 193.190.253.214 local addr 192.168.129.30 -> Nov 03 17:34:24 volumio ntpd[797]: 45.87.77.15 local addr 192.168.129.30 -> Nov 03 17:34:24 volumio systemd[1]: Stopping Network Time Service... Nov 03 17:34:24 volumio systemd[1]: Stopping Volumio Streaming Daemon... Nov 03 17:34:24 volumio systemd[1]: Stopping LSB: Brings up/down network automatically... Nov 03 17:34:24 volumio systemd[1]: Stopping volumio-remote-updater.service... Nov 03 17:34:24 volumio systemd[1]: Stopping Login Service... Nov 03 17:34:24 volumio systemd[1]: Stopping Samba SMB Daemon... Nov 03 17:34:24 volumio systemd[1]: Stopping UPnP Renderer front-end to MPD... Nov 03 17:34:24 volumio systemd[1]: Stopping triggerhappy global hotkey daemon... Nov 03 17:34:24 volumio systemd[1]: Stopping Music Player Daemon... Nov 03 17:34:24 volumio sshd[644]: Received signal 15; terminating. Nov 03 17:34:24 volumio systemd[1]: Stopping OpenBSD Secure Shell server... Nov 03 17:34:24 volumio systemd[1]: systemd-tmpfiles-clean.timer: Succeeded. Nov 03 17:34:24 volumio systemd[1]: Stopped Daily Cleanup of Temporary Directories. Nov 03 17:34:24 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 03 17:34:24 volumio systemd[1]: Stopped target Login Prompts. Nov 03 17:34:24 volumio systemd[1]: Stopping Getty on tty1... Nov 03 17:34:24 volumio systemd[1]: Stopped target RPC Port Mapper. Nov 03 17:34:24 volumio systemd[1]: Stopping LSB: set CPUFreq kernel parameters... Nov 03 17:34:24 volumio systemd[1]: Stopping Volumio Kiosk... Nov 03 17:34:24 volumio bluetoothd[719]: Stopping SDP server Nov 03 17:34:24 volumio bluetoothd[719]: Exit Nov 03 17:34:24 volumio startx[1013]: xinit: connection to X server lost Nov 03 17:34:24 volumio startx[1013]: [1B blob data] Nov 03 17:34:24 volumio dbus-daemon[504]: [system] Rejected send message, 1 matched rules; type="method_return", sender=":1.59" (uid=1000 pid=1792 comm="/usr/bin/python /bin/bt/simple-agent -c NoInputNoO") interface="(unset)" member="(unset)" error name="(unset)" requested_reply="0" destination=":1.5" (uid=0 pid=719 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Nov 03 17:34:24 volumio volumiobt[7946]: Killing BT Pair agent Nov 03 17:34:24 volumio systemd[1]: volumio-remote-updater.service: Main process exited, code=killed, status=15/TERM Nov 03 17:34:24 volumio volumiobt[1713]: Terminated Nov 03 17:34:24 volumio systemd[1]: volumio-remote-updater.service: Succeeded. Nov 03 17:34:24 volumio systemd[1]: Stopped volumio-remote-updater.service. Nov 03 17:34:24 volumio systemd[1]: getty@tty1.service: Main process exited, code=killed, status=15/TERM Nov 03 17:34:24 volumio systemd[1]: getty@tty1.service: Succeeded. Nov 03 17:34:24 volumio sudo[7958]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Nov 03 17:34:24 volumio systemd[1]: Stopped Getty on tty1. Nov 03 17:34:24 volumio sudo[7958]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 17:34:24 volumio systemd[1]: ssh.service: Succeeded. Nov 03 17:34:24 volumio systemd[1]: Stopped OpenBSD Secure Shell server. Nov 03 17:34:24 volumio systemd[1]: systemd-logind.service: Succeeded. Nov 03 17:34:24 volumio volumiobt[1713]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Nov 03 17:34:24 volumio systemd[1]: Stopped Login Service. Nov 03 17:34:24 volumio sudo[7958]: pam_unix(sudo:session): session closed for user root Nov 03 17:34:24 volumio systemd[1]: bluetooth.service: Succeeded. Nov 03 17:34:24 volumio systemd[1]: Stopped Bluetooth service. Nov 03 17:34:24 volumio systemd[1]: ntp.service: Succeeded. Nov 03 17:34:24 volumio systemd[1]: Stopped Network Time Service. Nov 03 17:34:24 volumio systemd[1]: smbd.service: Main process exited, code=killed, status=15/TERM Nov 03 17:34:24 volumio systemd[1]: smbd.service: Succeeded. Nov 03 17:34:24 volumio systemd[1]: Stopped Samba SMB Daemon. Nov 03 17:34:24 volumio systemd[1]: upmpdcli.service: Succeeded. Nov 03 17:34:24 volumio systemd[1]: Stopped UPnP Renderer front-end to MPD. Nov 03 17:34:24 volumio systemd[1]: volumiobt.service: Main process exited, code=exited, status=1/FAILURE Nov 03 17:34:24 volumio systemd[1]: rtkit-daemon.service: Main process exited, code=killed, status=15/TERM Nov 03 17:34:24 volumio systemd[1]: rtkit-daemon.service: Succeeded. Nov 03 17:34:24 volumio systemd[1]: Stopped RealtimeKit Scheduling Policy Service. Nov 03 17:34:24 volumio systemd[1]: polkit.service: Main process exited, code=killed, status=15/TERM Nov 03 17:34:24 volumio systemd[1]: polkit.service: Succeeded. Nov 03 17:34:24 volumio systemd[1]: Stopped Authorization Manager. Nov 03 17:34:24 volumio systemd[1]: volumio-streaming-daemon.service: Main process exited, code=killed, status=15/TERM Nov 03 17:34:24 volumio systemd[1]: volumio-streaming-daemon.service: Succeeded. Nov 03 17:34:24 volumio systemd[1]: Stopped Volumio Streaming Daemon. Nov 03 17:34:24 volumio systemd[1]: mpd.service: Succeeded. Nov 03 17:34:24 volumio systemd[1]: Stopped Music Player Daemon. Nov 03 17:34:24 volumio systemd[1]: shairport-sync.service: Succeeded. Nov 03 17:34:24 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 03 17:34:24 volumio systemd[1]: sshtunnel.service: Main process exited, code=killed, status=15/TERM Nov 03 17:34:24 volumio systemd[1]: sshtunnel.service: Succeeded. Nov 03 17:34:24 volumio systemd[1]: Stopped MyVolumio SSH Tunnel. Nov 03 17:34:24 volumio systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Nov 03 17:34:24 volumio systemd[1]: vtcs.service: Succeeded. Nov 03 17:34:24 volumio systemd[1]: Stopped Volumio Tidal Connect Service. Nov 03 17:34:24 volumio systemd[1]: run-rpc_pipefs.mount: Succeeded. Nov 03 17:34:24 volumio systemd[1]: Unmounted RPC Pipe File System. Nov 03 17:34:24 volumio systemd[1]: rpi-display-backlight.service: Succeeded. Nov 03 17:34:24 volumio systemd[1]: Started Turns off Raspberry Pi display backlight on shutdown/reboot. Nov 03 17:34:24 volumio systemd[1]: volumiobt.service: Failed with result 'exit-code'. Nov 03 17:34:24 volumio systemd[1]: Stopped Volumio Bluetooth Module. Nov 03 17:34:24 volumio systemd[1]: fusiondsp.service: Succeeded. Nov 03 17:34:24 volumio systemd[1]: Stopped FusionDsp Daemon. Nov 03 17:34:24 volumio avahi-daemon[502]: Got SIGTERM, quitting. Nov 03 17:34:24 volumio avahi-daemon[502]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.129.30. Nov 03 17:34:24 volumio systemd[1]: Stopping Avahi mDNS/DNS-SD Stack... Nov 03 17:34:24 volumio systemd[1]: Stopped target Sound Card. Nov 03 17:34:24 volumio systemd[1]: Stopping Save/Restore Sound Card State... Nov 03 17:34:24 volumio winbindd[884]: [2025/11/03 17:34:24.675673, 0] ../source3/winbindd/winbindd.c:244(winbindd_sig_term_handler) Nov 03 17:34:24 volumio winbindd[884]: Got sig[15] terminate (is_parent=1) Nov 03 17:34:24 volumio systemd[1]: Stopping Samba Winbind Daemon... Nov 03 17:34:24 volumio winbindd[893]: [2025/11/03 17:34:24.686293, 0] ../source3/winbindd/winbindd.c:244(winbindd_sig_term_handler) Nov 03 17:34:24 volumio systemd[1]: bthelper@hci0.service: Succeeded. Nov 03 17:34:24 volumio winbindd[893]: Got sig[15] terminate (is_parent=0) Nov 03 17:34:24 volumio systemd[1]: Stopped Raspberry Pi bluetooth helper. Nov 03 17:34:24 volumio systemd[1]: Stopping Configure Bluetooth Modems connected by UART... Nov 03 17:34:24 volumio systemd[1]: Removed slice system-bthelper.slice. Nov 03 17:34:24 volumio systemd[1]: Starting Show Plymouth Power Off Screen... Nov 03 17:34:24 volumio ifplugd(eth0)[636]: Exiting. Nov 03 17:34:24 volumio systemd[1]: Stopping Permit User Sessions... Nov 03 17:34:24 volumio systemd[1]: rc-local.service: Succeeded. Nov 03 17:34:24 volumio systemd[1]: Stopped /etc/rc.local Compatibility. Nov 03 17:34:24 volumio systemd[1]: Removed slice system-getty.slice. Nov 03 17:34:24 volumio systemd[1]: cpufrequtils.service: Succeeded. Nov 03 17:34:24 volumio systemd[1]: Stopped LSB: set CPUFreq kernel parameters. Nov 03 17:34:24 volumio avahi-daemon[502]: avahi-daemon 0.7 exiting. Nov 03 17:34:24 volumio systemd[1]: avahi-daemon.service: Succeeded. Nov 03 17:34:24 volumio systemd[1]: Stopped Avahi mDNS/DNS-SD Stack. Nov 03 17:34:24 volumio volumio[6675]: info: Shutting Down Nov 03 17:34:24 volumio systemd[1]: Received SIGRTMIN+20 from PID 7976 (plymouthd). Nov 03 17:34:24 volumio systemd[1]: winbind.service: Succeeded. Nov 03 17:34:24 volumio systemd[1]: Stopped Samba Winbind Daemon. Nov 03 17:34:24 volumio systemd[1]: triggerhappy.service: Succeeded. Nov 03 17:34:24 volumio systemd[1]: Stopped triggerhappy global hotkey daemon. Nov 03 17:34:24 volumio systemd[1]: alsa-restore.service: Succeeded. Nov 03 17:34:24 volumio systemd[1]: Stopped Save/Restore Sound Card State. Nov 03 17:34:24 volumio systemd[1]: systemd-user-sessions.service: Succeeded. Nov 03 17:34:24 volumio systemd[1]: Stopped Permit User Sessions. Nov 03 17:34:24 volumio systemd[1]: Stopping Manage Sound Card State (restore and store)... Nov 03 17:34:24 volumio systemd[1]: Stopping Samba NMB Daemon... Nov 03 17:34:24 volumio systemd[1]: Stopping LSB: Load kernel modules needed to enable cpufreq scaling... Nov 03 17:34:24 volumio systemd[1]: Started Show Plymouth Power Off Screen. Nov 03 17:34:24 volumio nmbd[621]: [2025/11/03 17:34:24.763344, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Nov 03 17:34:24 volumio nmbd[621]: started asyncdns process 7982 Nov 03 17:34:24 volumio alsactl[482]: alsactl daemon stopped Nov 03 17:34:24 volumio systemd[1]: alsa-state.service: Succeeded. Nov 03 17:34:24 volumio systemd[1]: Stopped Manage Sound Card State (restore and store). Nov 03 17:34:24 volumio volumio[6675]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 03 17:34:24 volumio volumio[6675]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 03 17:34:24 volumio volumio[6675]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 03 17:34:24 volumio volumio[6675]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 03 17:34:24 volumio volumio[6675]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 03 17:34:24 volumio volumio[6675]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 03 17:34:24 volumio volumio[6675]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 03 17:34:24 volumio volumio[6675]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 03 17:34:24 volumio volumio[6675]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 03 17:34:24 volumio volumio[6675]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 03 17:34:24 volumio volumio[6675]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 03 17:34:24 volumio volumio[6675]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 03 17:34:24 volumio volumio[6675]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 03 17:34:24 volumio volumio[6675]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 03 17:34:24 volumio volumio[6675]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 03 17:34:24 volumio volumio[6675]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 03 17:34:24 volumio systemd[1]: hciuart.service: Succeeded. Nov 03 17:34:24 volumio volumio[6675]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 03 17:34:24 volumio volumio[6675]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 03 17:34:24 volumio systemd[1]: Stopped Configure Bluetooth Modems connected by UART. Nov 03 17:34:24 volumio ifplugd[7949]: Network Interface Plugging Daemon...stop eth0...done. Nov 03 17:34:24 volumio systemd[1]: ifplugd.service: Succeeded. Nov 03 17:34:24 volumio systemd[1]: Stopped LSB: Brings up/down network automatically. Nov 03 17:34:24 volumio systemd[1]: loadcpufreq.service: Succeeded. Nov 03 17:34:24 volumio systemd[1]: Stopped LSB: Load kernel modules needed to enable cpufreq scaling. Nov 03 17:34:24 volumio systemd[1]: Stopped target Remote File Systems. Nov 03 17:34:24 volumio systemd[1]: Stopped target Remote File Systems (Pre). Nov 03 17:34:24 volumio systemd[1]: Stopped target NFS client services. Nov 03 17:34:24 volumio startx[1013]: waiting for X server to shut down (II) Server terminated successfully (0). Closing log file. Nov 03 17:34:24 volumio volumio[6675]: info: Discovery: Browse raised the following error Error: dns service error: unknown Nov 03 17:34:24 volumio volumio[6675]: error: Discovery: Advertisement error: Error: dns service error: unknown Nov 03 17:34:24 volumio volumio[6675]: error: Discovery: advertisement error: Error: dns service error: unknown Nov 03 17:34:24 volumio volumio[6675]: info: Discovery: Advertisement raised the following error Error: dns service error: unknown Nov 03 17:34:24 volumio volumio[6675]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 03 17:34:24 volumio volumio[6675]: Error: dns service error: unknown Nov 03 17:34:24 volumio volumio[6675]: at Browser.on_service_changed (/volumio/node_modules/mdns/lib/browser.js:84:33) Nov 03 17:34:24 volumio volumio[6675]: at SocketWatcher.MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Nov 03 17:34:24 volumio volumio[6675]: errorCode: -65537 Nov 03 17:34:24 volumio volumio[6675]: } Nov 03 17:34:24 volumio volumio[6675]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 03 17:34:25 volumio sudo[7996]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-11-03 17:33 Nov 03 17:34:25 volumio sudo[7996]: 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="91e88ea6c5c4ec4f00aff63f7c3a2944b14ec939" VOLUMIO_FE_VERSION="c433755e569617abac52264d17ad61be303ef463" VOLUMIO_FE3_VERSION="2419ddd8e895c5bdc456962163c9dc7c0f187aa7" VOLUMIO_BE_VERSION="8e6823482d6af342172ec9fdc1c15b0dd02653d2" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sun 04 Dec 2022 08:41:29 PM CET" VOLUMIO_VERSION="3.396" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d460c75d04818d0f55c67627fa362668"