-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Thu 2026-10-15 20:18:20 CEST. -- Oct 15 20:17:18 volumio1 volumio5-onboarding[1053]: time=2026-10-15T20:17:18.016+02:00 level=INFO msg="player pause request" component=server type=REQUEST_TYPE_PLAYER_PAUSE peer="00:00:00:00:00:00%03 @ 0x249b320" latency=-687.818544ms timeout=10s Oct 15 20:17:18 volumio1 volumio[816]: info: CoreCommandRouter::volumioPause Oct 15 20:17:18 volumio1 volumio[816]: info: CoreStateMachine::pause Oct 15 20:17:18 volumio1 volumio[816]: info: CoreStateMachine::stPlaybackTimer Oct 15 20:17:18 volumio1 volumio[816]: info: CoreStateMachine::servicePause Oct 15 20:17:18 volumio1 volumio[816]: info: CorePlayQueue::getTrack 17 Oct 15 20:17:18 volumio1 volumio[816]: info: CoreCommandRouter::servicePause Oct 15 20:17:18 volumio1 volumio[816]: info: ControllerMpd::pause Oct 15 20:17:18 volumio1 volumio[816]: verbose: ControllerMpd::sendMpdCommand pause Oct 15 20:17:18 volumio1 volumio[816]: info: sendMpdCommand pause took 1 milliseconds Oct 15 20:17:18 volumio1 volumio[816]: info: Oct 15 20:17:18 volumio1 volumio[816]: ---------------------------- MPD announces state update: player Oct 15 20:17:18 volumio1 volumio[816]: info: ControllerMpd::getState Oct 15 20:17:18 volumio1 volumio[816]: verbose: ControllerMpd::sendMpdCommand status Oct 15 20:17:18 volumio1 volumio[816]: info: Oct 15 20:17:18 volumio1 volumio[816]: ---------------------------- MPD announces state update: player Oct 15 20:17:18 volumio1 volumio[816]: info: ControllerMpd::getState Oct 15 20:17:18 volumio1 volumio[816]: verbose: ControllerMpd::sendMpdCommand status Oct 15 20:17:18 volumio1 volumio[816]: info: Oct 15 20:17:18 volumio1 volumio[816]: ---------------------------- MPD announces state update: player Oct 15 20:17:18 volumio1 volumio[816]: info: ControllerMpd::getState Oct 15 20:17:18 volumio1 volumio[816]: verbose: ControllerMpd::sendMpdCommand status Oct 15 20:17:18 volumio1 volumio[816]: info: Oct 15 20:17:18 volumio1 volumio[816]: ---------------------------- MPD announces state update: player Oct 15 20:17:18 volumio1 volumio[816]: info: ControllerMpd::getState Oct 15 20:17:18 volumio1 volumio[816]: verbose: ControllerMpd::sendMpdCommand status Oct 15 20:17:18 volumio1 volumio[816]: info: sendMpdCommand status took 2 milliseconds Oct 15 20:17:18 volumio1 volumio[816]: info: sendMpdCommand status took 3 milliseconds Oct 15 20:17:18 volumio1 volumio[816]: info: sendMpdCommand status took 2 milliseconds Oct 15 20:17:18 volumio1 volumio[816]: verbose: ControllerMpd::parseState Oct 15 20:17:18 volumio1 volumio[816]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 15 20:17:18 volumio1 volumio[816]: verbose: ControllerMpd::parseState Oct 15 20:17:18 volumio1 volumio[816]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 15 20:17:18 volumio1 volumio[816]: verbose: ControllerMpd::parseState Oct 15 20:17:18 volumio1 volumio[816]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 15 20:17:18 volumio1 volumio[816]: info: sendMpdCommand status took 2 milliseconds Oct 15 20:17:18 volumio1 volumio[816]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 15 20:17:18 volumio1 volumio[816]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 15 20:17:18 volumio1 volumio[816]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 15 20:17:18 volumio1 volumio[816]: verbose: ControllerMpd::parseState Oct 15 20:17:18 volumio1 volumio[816]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 15 20:17:18 volumio1 volumio[816]: verbose: ControllerMpd::parseTrackInfo Oct 15 20:17:18 volumio1 volumio[816]: verbose: ControllerMpd::parseTrackInfo Oct 15 20:17:18 volumio1 volumio[816]: verbose: ControllerMpd::parseTrackInfo Oct 15 20:17:18 volumio1 volumio[816]: info: ControllerMpd::pushState Oct 15 20:17:18 volumio1 volumio[816]: info: CoreCommandRouter::servicePushState Oct 15 20:17:18 volumio1 volumio[816]: info: CorePlayQueue::getTrack 17 Oct 15 20:17:18 volumio1 volumio[816]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":161714,"duration":224,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"920 Kbps","isStreaming":false,"title":"See Me","artist":"Roxette","album":"Crash! Boom! Bang! (Extended Version)","uri":"NAS/QNAP212/R/ROXETTE/Roxette - Crash! Boom! Bang! (Extended Version) [134545699] [1994]/CD2/03 - Roxette - See Me.flac","trackType":"flac"} Oct 15 20:17:18 volumio1 volumio[816]: verbose: CURRENT POSITION 17 Oct 15 20:17:18 volumio1 volumio[816]: info: CoreStateMachine::syncState stateService pause Oct 15 20:17:18 volumio1 volumio[816]: info: CoreStateMachine::syncState currentStatus pause Oct 15 20:17:18 volumio1 volumio[816]: info: CoreStateMachine::pushState Oct 15 20:17:18 volumio1 volumio[816]: info: CorePlayQueue::getTrack 17 Oct 15 20:17:18 volumio1 volumio[816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 15 20:17:18 volumio1 volumio[816]: info: CoreCommandRouter::volumioPushState Oct 15 20:17:18 volumio1 volumio[816]: info: CoreStateMachine::stPlaybackTimer Oct 15 20:17:18 volumio1 volumio[816]: info: ControllerMpd::pushState Oct 15 20:17:18 volumio1 volumio[816]: info: CoreCommandRouter::servicePushState Oct 15 20:17:18 volumio1 volumio[816]: info: CorePlayQueue::getTrack 17 Oct 15 20:17:18 volumio1 volumio[816]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":161714,"duration":224,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"920 Kbps","isStreaming":false,"title":"See Me","artist":"Roxette","album":"Crash! Boom! Bang! (Extended Version)","uri":"NAS/QNAP212/R/ROXETTE/Roxette - Crash! Boom! Bang! (Extended Version) [134545699] [1994]/CD2/03 - Roxette - See Me.flac","trackType":"flac"} Oct 15 20:17:18 volumio1 volumio[816]: verbose: CURRENT POSITION 17 Oct 15 20:17:18 volumio1 volumio[816]: info: CoreStateMachine::syncState stateService pause Oct 15 20:17:18 volumio1 volumio[816]: info: CoreStateMachine::syncState currentStatus pause Oct 15 20:17:18 volumio1 volumio[816]: info: CoreStateMachine::pushState Oct 15 20:17:18 volumio1 volumio[816]: info: CorePlayQueue::getTrack 17 Oct 15 20:17:18 volumio1 volumio[816]: info: CoreCommandRouter::volumioPushState Oct 15 20:17:18 volumio1 volumio[816]: info: CoreStateMachine::stPlaybackTimer Oct 15 20:17:18 volumio1 volumio[816]: info: ControllerMpd::pushState Oct 15 20:17:18 volumio1 volumio[816]: info: CoreCommandRouter::servicePushState Oct 15 20:17:18 volumio1 volumio[816]: info: CorePlayQueue::getTrack 17 Oct 15 20:17:18 volumio1 volumio[816]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":161714,"duration":224,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"920 Kbps","isStreaming":false,"title":"See Me","artist":"Roxette","album":"Crash! Boom! Bang! (Extended Version)","uri":"NAS/QNAP212/R/ROXETTE/Roxette - Crash! Boom! Bang! (Extended Version) [134545699] [1994]/CD2/03 - Roxette - See Me.flac","trackType":"flac"} Oct 15 20:17:18 volumio1 volumio[816]: verbose: CURRENT POSITION 17 Oct 15 20:17:18 volumio1 volumio[816]: info: CoreStateMachine::syncState stateService pause Oct 15 20:17:18 volumio1 volumio[816]: info: CoreStateMachine::syncState currentStatus pause Oct 15 20:17:18 volumio1 volumio[816]: info: CoreStateMachine::pushState Oct 15 20:17:18 volumio1 volumio[816]: info: CorePlayQueue::getTrack 17 Oct 15 20:17:18 volumio1 volumio[816]: info: CoreCommandRouter::volumioPushState Oct 15 20:17:18 volumio1 volumio[816]: info: CoreStateMachine::stPlaybackTimer Oct 15 20:17:18 volumio1 volumio5-onboarding[1053]: time=2026-10-15T20:17:18.043+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.33:39964 @ 0x20cb080" state=STATUS_PAUSED positionMs=161676 volume=26 Oct 15 20:17:18 volumio1 volumio5-onboarding[1053]: time=2026-10-15T20:17:18.043+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%03 @ 0x249b320" state=STATUS_PAUSED positionMs=161676 volume=26 Oct 15 20:17:18 volumio1 volumio5-onboarding[1053]: time=2026-10-15T20:17:18.044+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.33:39964 @ 0x20cb080" state=STATUS_PAUSED positionMs=161676 volume=26 Oct 15 20:17:18 volumio1 volumio5-onboarding[1053]: time=2026-10-15T20:17:18.044+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%03 @ 0x249b320" state=STATUS_PAUSED positionMs=161676 volume=26 Oct 15 20:17:18 volumio1 volumio5-onboarding[1053]: time=2026-10-15T20:17:18.044+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.33:39964 @ 0x20cb080" state=STATUS_PAUSED positionMs=161676 volume=26 Oct 15 20:17:18 volumio1 volumio5-onboarding[1053]: time=2026-10-15T20:17:18.045+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%03 @ 0x249b320" state=STATUS_PAUSED positionMs=161676 volume=26 Oct 15 20:17:18 volumio1 volumio[816]: info: ------------------------------ 30ms Oct 15 20:17:18 volumio1 volumio[816]: info: ------------------------------ 30ms Oct 15 20:17:18 volumio1 volumio[816]: info: ------------------------------ 29ms Oct 15 20:17:18 volumio1 volumio[816]: info: sendMpdCommand playlistinfo took 27 milliseconds Oct 15 20:17:18 volumio1 volumio[816]: verbose: ControllerMpd::parseTrackInfo Oct 15 20:17:18 volumio1 volumio[816]: info: ControllerMpd::pushState Oct 15 20:17:18 volumio1 volumio[816]: info: CoreCommandRouter::servicePushState Oct 15 20:17:18 volumio1 volumio[816]: info: CorePlayQueue::getTrack 17 Oct 15 20:17:18 volumio1 volumio[816]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":161714,"duration":224,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"920 Kbps","isStreaming":false,"title":"See Me","artist":"Roxette","album":"Crash! Boom! Bang! (Extended Version)","uri":"NAS/QNAP212/R/ROXETTE/Roxette - Crash! Boom! Bang! (Extended Version) [134545699] [1994]/CD2/03 - Roxette - See Me.flac","trackType":"flac"} Oct 15 20:17:18 volumio1 volumio[816]: verbose: CURRENT POSITION 17 Oct 15 20:17:18 volumio1 volumio[816]: info: CoreStateMachine::syncState stateService pause Oct 15 20:17:18 volumio1 volumio[816]: info: CoreStateMachine::syncState currentStatus pause Oct 15 20:17:18 volumio1 volumio[816]: info: CoreStateMachine::pushState Oct 15 20:17:18 volumio1 volumio[816]: info: CorePlayQueue::getTrack 17 Oct 15 20:17:18 volumio1 volumio[816]: info: CoreCommandRouter::volumioPushState Oct 15 20:17:18 volumio1 volumio[816]: info: CoreStateMachine::stPlaybackTimer Oct 15 20:17:18 volumio1 volumio5-onboarding[1053]: time=2026-10-15T20:17:18.064+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.33:39964 @ 0x20cb080" state=STATUS_PAUSED positionMs=161676 volume=26 Oct 15 20:17:18 volumio1 volumio5-onboarding[1053]: time=2026-10-15T20:17:18.064+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%03 @ 0x249b320" state=STATUS_PAUSED positionMs=161676 volume=26 Oct 15 20:17:18 volumio1 volumio[816]: info: ------------------------------ 46ms Oct 15 20:17:18 volumio1 volumio[816]: info: touch_display: Setting screensaver timeout to 120 seconds. Oct 15 20:17:18 volumio1 volumio5-onboarding[1053]: time=2026-10-15T20:17:18.135+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.33:39964 @ 0x20cb080" id="mnt/NAS/QNAP212/R/ROXETTE/Roxette - Crash! Boom! Bang! (Extended Version) [134545699] [1994]/CD2/03 - Roxette - See Me.flac" title="3 - See Me" Oct 15 20:17:18 volumio1 volumio5-onboarding[1053]: time=2026-10-15T20:17:18.136+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%03 @ 0x249b320" id="mnt/NAS/QNAP212/R/ROXETTE/Roxette - Crash! Boom! Bang! (Extended Version) [134545699] [1994]/CD2/03 - Roxette - See Me.flac" title="3 - See Me" Oct 15 20:17:18 volumio1 volumio5-onboarding[1053]: time=2026-10-15T20:17:18.196+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.33:39964 @ 0x20cb080" id="mnt/NAS/QNAP212/R/ROXETTE/Roxette - Crash! Boom! Bang! (Extended Version) [134545699] [1994]/CD2/03 - Roxette - See Me.flac" title="3 - See Me" Oct 15 20:17:18 volumio1 volumio5-onboarding[1053]: time=2026-10-15T20:17:18.196+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%03 @ 0x249b320" id="mnt/NAS/QNAP212/R/ROXETTE/Roxette - Crash! Boom! Bang! (Extended Version) [134545699] [1994]/CD2/03 - Roxette - See Me.flac" title="3 - See Me" Oct 15 20:17:18 volumio1 volumio5-onboarding[1053]: time=2026-10-15T20:17:18.256+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.33:39964 @ 0x20cb080" id="mnt/NAS/QNAP212/R/ROXETTE/Roxette - Crash! Boom! Bang! (Extended Version) [134545699] [1994]/CD2/03 - Roxette - See Me.flac" title="3 - See Me" Oct 15 20:17:18 volumio1 volumio5-onboarding[1053]: time=2026-10-15T20:17:18.256+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%03 @ 0x249b320" id="mnt/NAS/QNAP212/R/ROXETTE/Roxette - Crash! Boom! Bang! (Extended Version) [134545699] [1994]/CD2/03 - Roxette - See Me.flac" title="3 - See Me" Oct 15 20:17:18 volumio1 volumio5-onboarding[1053]: time=2026-10-15T20:17:18.315+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.33:39964 @ 0x20cb080" id="mnt/NAS/QNAP212/R/ROXETTE/Roxette - Crash! Boom! Bang! (Extended Version) [134545699] [1994]/CD2/03 - Roxette - See Me.flac" title="3 - See Me" Oct 15 20:17:18 volumio1 volumio5-onboarding[1053]: time=2026-10-15T20:17:18.316+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%03 @ 0x249b320" id="mnt/NAS/QNAP212/R/ROXETTE/Roxette - Crash! Boom! Bang! (Extended Version) [134545699] [1994]/CD2/03 - Roxette - See Me.flac" title="3 - See Me" Oct 15 20:17:54 volumio1 volumio[816]: verbose: New Socket.io Connection to 192.168.1.13 from 192.168.1.80 UA: Mozilla/5.0 (Linux; Android 16; PGT-N19 Build/HONORPGT-N49; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/154.0.8037.106 Mobile Safari/537.36 VolumioApp/6.260930.0 Engine version: 3 Transport: polling Total Clients: 8 Oct 15 20:17:54 volumio1 volumio5-onboarding[1053]: time=2026-10-15T20:17:54.125+02:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.1.80:43080 Oct 15 20:17:54 volumio1 volumio[816]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 15 20:17:54 volumio1 volumio[816]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 15 20:17:54 volumio1 volumio[816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 15 20:17:54 volumio1 volumio[816]: info: Discovery: Getting this device information Oct 15 20:17:54 volumio1 volumio[816]: info: CoreCommandRouter::volumioGetState Oct 15 20:17:54 volumio1 volumio[816]: info: CorePlayQueue::getTrack 17 Oct 15 20:17:54 volumio1 volumio[816]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 15 20:17:54 volumio1 volumio[816]: info: CoreCommandRouter::volumioGetVisibleSources Oct 15 20:17:54 volumio1 volumio[816]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 15 20:17:54 volumio1 volumio[816]: info: CoreCommandRouter::volumioGetState Oct 15 20:17:54 volumio1 volumio[816]: info: CorePlayQueue::getTrack 17 Oct 15 20:17:54 volumio1 volumio[816]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 15 20:17:54 volumio1 volumio[816]: info: CoreCommandRouter::volumioGetQueue Oct 15 20:17:54 volumio1 volumio[816]: info: CoreStateMachine::getQueue Oct 15 20:17:54 volumio1 volumio[816]: info: CorePlayQueue::getQueue Oct 15 20:17:54 volumio1 volumio[816]: info: Listing playlists Oct 15 20:17:54 volumio1 volumio[816]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 15 20:17:54 volumio1 volumio[816]: info: Received Get System Info Oct 15 20:17:54 volumio1 volumio[816]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 15 20:17:54 volumio1 volumio[816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 15 20:17:54 volumio1 volumio[816]: info: Discovery: Getting this device information Oct 15 20:17:54 volumio1 volumio[816]: info: CoreCommandRouter::volumioGetState Oct 15 20:17:54 volumio1 volumio[816]: info: CorePlayQueue::getTrack 17 Oct 15 20:17:54 volumio1 volumio5-onboarding[1053]: time=2026-10-15T20:17:54.180+02:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.1.80:43080 @ 0x2000180" latency=-709.605665ms timeout=20s Oct 15 20:17:54 volumio1 volumio5-onboarding[1053]: time=2026-10-15T20:17:54.180+02:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.1.80:43080 @ 0x2000180" Oct 15 20:17:54 volumio1 volumio[816]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 15 20:17:54 volumio1 volumio[816]: info: CoreCommandRouter::volumioGetState Oct 15 20:17:54 volumio1 volumio[816]: info: CorePlayQueue::getTrack 17 Oct 15 20:17:54 volumio1 volumio5-onboarding[1053]: time=2026-10-15T20:17:54.182+02:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.80:43080 @ 0x2000180" latency=-708.555447ms timeout=10s Oct 15 20:17:54 volumio1 volumio[816]: verbose: New Socket.io Connection to 192.168.1.13:3000 from 192.168.1.80 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Oct 15 20:17:54 volumio1 volumio[816]: info: Received Get System Info Oct 15 20:17:54 volumio1 volumio[816]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 15 20:17:54 volumio1 volumio[816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 15 20:17:54 volumio1 volumio[816]: info: Discovery: Getting this device information Oct 15 20:17:54 volumio1 volumio[816]: info: CoreCommandRouter::volumioGetState Oct 15 20:17:54 volumio1 volumio[816]: info: CorePlayQueue::getTrack 17 Oct 15 20:17:54 volumio1 volumio[816]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 15 20:17:54 volumio1 volumio5-onboarding[1053]: time=2026-10-15T20:17:54.199+02:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.1.80:43080 @ 0x2000180" name=Volumio1 Oct 15 20:17:54 volumio1 volumio5-onboarding[1053]: time=2026-10-15T20:17:54.199+02:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.1.80:43080 @ 0x2000180" language=it Oct 15 20:17:54 volumio1 volumio[816]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 15 20:17:54 volumio1 volumio[816]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 15 20:17:54 volumio1 volumio[816]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Oct 15 20:17:54 volumio1 volumio5-onboarding[1053]: time=2026-10-15T20:17:54.205+02:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.1.80:43080 @ 0x2000180" timezone=Europe/Rome Oct 15 20:17:54 volumio1 volumio5-onboarding[1053]: time=2026-10-15T20:17:54.206+02:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.1.80:43080 @ 0x2000180" available=true connected=true macAddress=dc:a6:32:0b:f3:02 ip4Address=192.168.1.13/24 ip6Address= Oct 15 20:17:54 volumio1 volumio5-onboarding[1053]: time=2026-10-15T20:17:54.208+02:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.80:43080 @ 0x2000180" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Oct 15 20:17:54 volumio1 volumio5-onboarding[1053]: time=2026-10-15T20:17:54.208+02:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.1.80:43080 @ 0x2000180" setupComplete=true Oct 15 20:17:54 volumio1 volumio[816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Oct 15 20:17:54 volumio1 volumio[816]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 15 20:17:54 volumio1 volumio[816]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 20:17:54 volumio1 volumio[816]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 15 20:17:54 volumio1 volumio[816]: amixer -c 2 info | grep "snd_rpi_hifiberry_dacplus" Oct 15 20:17:54 volumio1 volumio[816]: Card hw:2 'sndrpihifiberry'/'snd_rpi_hifiberry_dacplus' Oct 15 20:17:54 volumio1 volumio[816]: amixer -c 0 info | grep "bcm2835 ALSA" Oct 15 20:17:54 volumio1 volumio5-onboarding[1053]: time=2026-10-15T20:17:54.328+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.80:43080 @ 0x2000180" latency=-708.555447ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=145.883298ms Oct 15 20:17:54 volumio1 volumio5-onboarding[1053]: time=2026-10-15T20:17:54.345+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.80:43080 @ 0x2000180" latency=-708.555447ms timeout=10s endpoint=http://pushupdates.volumio.org duration=104.01129ms Oct 15 20:17:54 volumio1 volumio[816]: amixer -c 1 info | grep "bcm2835 Headphones" Oct 15 20:17:54 volumio1 volumio5-onboarding[1053]: time=2026-10-15T20:17:54.376+02:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.80:43080 @ 0x2000180" latency=-708.555447ms timeout=10s endpoint=http://cddb.volumio.org duration=193.251504ms error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF" Oct 15 20:17:54 volumio1 volumio5-onboarding[1053]: time=2026-10-15T20:17:54.397+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.80:43080 @ 0x2000180" latency=-708.555447ms timeout=10s endpoint=https://www.googleapis.com duration=212.979634ms Oct 15 20:17:54 volumio1 volumio[816]: Card hw:1 'Headphones'/'bcm2835 Headphones' Oct 15 20:17:54 volumio1 volumio[816]: amixer -c 2 info | grep "snd_rpi_hifiberry_dacplus" Oct 15 20:17:54 volumio1 volumio5-onboarding[1053]: time=2026-10-15T20:17:54.439+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.80:43080 @ 0x2000180" latency=-708.555447ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=255.241935ms Oct 15 20:17:54 volumio1 volumio[816]: Card hw:2 'sndrpihifiberry'/'snd_rpi_hifiberry_dacplus' Oct 15 20:17:54 volumio1 volumio5-onboarding[1053]: time=2026-10-15T20:17:54.441+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.80:43080 @ 0x2000180" latency=-708.555447ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=259.033928ms Oct 15 20:17:54 volumio1 volumio5-onboarding[1053]: time=2026-10-15T20:17:54.442+02:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.1.80:43080 @ 0x2000180" selectedOutputId=2 Oct 15 20:17:54 volumio1 volumio[816]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 15 20:17:54 volumio1 volumio[816]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 15 20:17:54 volumio1 volumio[816]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 15 20:17:54 volumio1 volumio[816]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 15 20:17:54 volumio1 volumio[816]: info: Received Get System Info Oct 15 20:17:54 volumio1 volumio[816]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 15 20:17:54 volumio1 volumio[816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 15 20:17:54 volumio1 volumio[816]: info: Discovery: Getting this device information Oct 15 20:17:54 volumio1 volumio[816]: info: CoreCommandRouter::volumioGetState Oct 15 20:17:54 volumio1 volumio[816]: info: CorePlayQueue::getTrack 17 Oct 15 20:17:54 volumio1 volumio[816]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 15 20:17:54 volumio1 volumio5-onboarding[1053]: time=2026-10-15T20:17:54.508+02:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.1.80:43080 @ 0x2000180" currentVersion=3.912 latestVersion=3.912 Oct 15 20:17:54 volumio1 volumio5-onboarding[1053]: time=2026-10-15T20:17:54.509+02:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.1.80:43080 @ 0x2000180" status=UPDATE_STATUS_NONE progress=0 Oct 15 20:17:54 volumio1 volumio5-onboarding[1053]: time=2026-10-15T20:17:54.509+02:00 level=INFO msg="emitting user changed event" component=server peer="192.168.1.80:43080 @ 0x2000180" userId= Oct 15 20:17:54 volumio1 volumio5-onboarding[1053]: time=2026-10-15T20:17:54.509+02:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.1.80:43080 @ 0x2000180" providers=3 Oct 15 20:17:54 volumio1 volumio5-onboarding[1053]: time=2026-10-15T20:17:54.509+02:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.1.80:43080 @ 0x2000180" plugins=54 Oct 15 20:17:54 volumio1 volumio[816]: info: CoreCommandRouter::volumioGetState Oct 15 20:17:54 volumio1 volumio[816]: info: CorePlayQueue::getTrack 17 Oct 15 20:17:54 volumio1 volumio5-onboarding[1053]: time=2026-10-15T20:17:54.514+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.80:43080 @ 0x2000180" state=STATUS_PAUSED positionMs=161926 volume=26 Oct 15 20:17:54 volumio1 volumio5-onboarding[1053]: time=2026-10-15T20:17:54.514+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.80:43080 @ 0x2000180" id="mnt/NAS/QNAP212/R/ROXETTE/Roxette - Crash! Boom! Bang! (Extended Version) [134545699] [1994]/CD2/03 - Roxette - See Me.flac" title="3 - See Me" Oct 15 20:17:54 volumio1 volumio[816]: info: FusionDsp - Utah5 Oct 15 20:17:54 volumio1 volumio5-onboarding[1053]: time=2026-10-15T20:17:54.522+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.80:43080 @ 0x2000180" latency=-708.555447ms timeout=10s endpoint=https://securetoken.googleapis.com duration=338.004278ms Oct 15 20:17:54 volumio1 volumio5-onboarding[1053]: time=2026-10-15T20:17:54.545+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.80:43080 @ 0x2000180" latency=-708.555447ms timeout=10s endpoint=http://plugins.volumio.org duration=361.137535ms Oct 15 20:17:54 volumio1 volumio5-onboarding[1053]: time=2026-10-15T20:17:54.545+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.80:43080 @ 0x2000180" latency=-708.555447ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=360.539246ms Oct 15 20:17:54 volumio1 volumio[816]: info: FusionDsp - Utah5 Oct 15 20:17:54 volumio1 volumio5-onboarding[1053]: time=2026-10-15T20:17:54.666+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.80:43080 @ 0x2000180" latency=-708.555447ms timeout=10s endpoint=https://database.volumio.cloud duration=483.239807ms Oct 15 20:17:54 volumio1 volumio5-onboarding[1053]: time=2026-10-15T20:17:54.672+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.80:43080 @ 0x2000180" latency=-708.555447ms timeout=10s endpoint=https://functions.volumio.cloud duration=488.21812ms Oct 15 20:17:54 volumio1 volumio5-onboarding[1053]: time=2026-10-15T20:17:54.683+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.80:43080 @ 0x2000180" latency=-708.555447ms timeout=10s endpoint=https://functions.volumio.cloud duration=500.107356ms Oct 15 20:17:54 volumio1 volumio[816]: info: FusionDsp - Utah5 Oct 15 20:17:54 volumio1 volumio[816]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json,Soggiorno.json,Utah5.json Oct 15 20:17:54 volumio1 volumio[816]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json,Soggiorno.json,Utah5.json Oct 15 20:17:54 volumio1 volumio[816]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json,Soggiorno.json,Utah5.json Oct 15 20:17:55 volumio1 volumio5-onboarding[1053]: time=2026-10-15T20:17:55.035+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.80:43080 @ 0x2000180" latency=-708.555447ms timeout=10s endpoint=https://google.com duration=851.393263ms Oct 15 20:17:56 volumio1 volumio5-onboarding[1053]: time=2026-10-15T20:17:56.213+02:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="00:00:00:00:00:00%03,192.168.1.80:43080 @ 0x249b320" latency=-708.823413ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Oct 15 20:17:56 volumio1 volumio5-onboarding[1053]: time=2026-10-15T20:17:56.240+02:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%03,192.168.1.80:43080 @ 0x249b320" latency=-704.608763ms timeout=10s Oct 15 20:17:56 volumio1 volumio5-onboarding[1053]: time=2026-10-15T20:17:56.253+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%03,192.168.1.80:43080 @ 0x249b320" latency=-704.608763ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=12.691246ms Oct 15 20:17:56 volumio1 volumio5-onboarding[1053]: time=2026-10-15T20:17:56.264+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%03,192.168.1.80:43080 @ 0x249b320" latency=-704.608763ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=23.40444ms Oct 15 20:17:56 volumio1 volumio5-onboarding[1053]: time=2026-10-15T20:17:56.277+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%03,192.168.1.80:43080 @ 0x249b320" latency=-704.608763ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=36.749364ms Oct 15 20:17:56 volumio1 volumio5-onboarding[1053]: time=2026-10-15T20:17:56.285+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%03,192.168.1.80:43080 @ 0x249b320" latency=-704.608763ms timeout=10s endpoint=http://pushupdates.volumio.org duration=44.519868ms Oct 15 20:17:56 volumio1 volumio5-onboarding[1053]: time=2026-10-15T20:17:56.339+02:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%03,192.168.1.80:43080 @ 0x249b320" latency=-704.608763ms timeout=10s endpoint=http://cddb.volumio.org duration=99.050864ms error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF" Oct 15 20:17:56 volumio1 volumio5-onboarding[1053]: time=2026-10-15T20:17:56.353+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%03,192.168.1.80:43080 @ 0x249b320" latency=-704.608763ms timeout=10s endpoint=https://www.googleapis.com duration=112.056902ms Oct 15 20:17:56 volumio1 volumio5-onboarding[1053]: time=2026-10-15T20:17:56.375+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%03,192.168.1.80:43080 @ 0x249b320" latency=-704.608763ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=134.419631ms Oct 15 20:17:56 volumio1 volumio5-onboarding[1053]: time=2026-10-15T20:17:56.390+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%03,192.168.1.80:43080 @ 0x249b320" latency=-704.608763ms timeout=10s endpoint=https://securetoken.googleapis.com duration=149.684792ms Oct 15 20:17:56 volumio1 volumio5-onboarding[1053]: time=2026-10-15T20:17:56.440+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%03,192.168.1.80:43080 @ 0x249b320" latency=-704.608763ms timeout=10s endpoint=http://plugins.volumio.org duration=199.586655ms Oct 15 20:17:56 volumio1 volumio5-onboarding[1053]: time=2026-10-15T20:17:56.493+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%03,192.168.1.80:43080 @ 0x249b320" latency=-704.608763ms timeout=10s endpoint=https://functions.volumio.cloud duration=252.637872ms Oct 15 20:17:56 volumio1 volumio5-onboarding[1053]: time=2026-10-15T20:17:56.494+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%03,192.168.1.80:43080 @ 0x249b320" latency=-704.608763ms timeout=10s endpoint=https://functions.volumio.cloud duration=252.935553ms Oct 15 20:17:56 volumio1 sudo[11500]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 15 20:17:56 volumio1 sudo[11500]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 15 20:17:56 volumio1 sudo[11500]: pam_unix(sudo:session): session closed for user root Oct 15 20:17:56 volumio1 sudo[11502]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 15 20:17:56 volumio1 sudo[11502]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 15 20:17:56 volumio1 sudo[11502]: pam_unix(sudo:session): session closed for user root Oct 15 20:17:56 volumio1 volumio[816]: verbose: New Socket.io Connection to 192.168.1.13 from 192.168.1.80 UA: Mozilla/5.0 (Linux; Android 16; PGT-N19 Build/HONORPGT-N49; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/154.0.8037.106 Mobile Safari/537.36 VolumioApp/6.260930.0 Engine version: 3 Transport: polling Total Clients: 8 Oct 15 20:17:56 volumio1 volumio5-onboarding[1053]: time=2026-10-15T20:17:56.664+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%03,192.168.1.80:43080 @ 0x249b320" latency=-704.608763ms timeout=10s endpoint=https://database.volumio.cloud duration=423.038392ms Oct 15 20:17:56 volumio1 sudo[11506]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 15 20:17:56 volumio1 sudo[11506]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 15 20:17:56 volumio1 sudo[11506]: pam_unix(sudo:session): session closed for user root Oct 15 20:17:56 volumio1 sudo[11509]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 15 20:17:56 volumio1 sudo[11509]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 15 20:17:56 volumio1 sudo[11509]: pam_unix(sudo:session): session closed for user root Oct 15 20:17:56 volumio1 volumio[816]: verbose: New Socket.io Connection to 192.168.1.13 from 192.168.1.80 UA: Mozilla/5.0 (Linux; Android 16; PGT-N19 Build/HONORPGT-N49; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/154.0.8037.106 Mobile Safari/537.36 VolumioApp/6.260930.0 Engine version: 3 Transport: polling Total Clients: 9 Oct 15 20:17:56 volumio1 volumio[816]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 15 20:17:56 volumio1 volumio[816]: info: CoreCommandRouter::volumioGetVisibleSources Oct 15 20:17:56 volumio1 volumio[816]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 15 20:17:56 volumio1 volumio[816]: info: CoreCommandRouter::volumioGetState Oct 15 20:17:56 volumio1 volumio[816]: info: CorePlayQueue::getTrack 17 Oct 15 20:17:56 volumio1 volumio[816]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 15 20:17:56 volumio1 volumio[816]: info: CoreCommandRouter::volumioGetQueue Oct 15 20:17:56 volumio1 volumio[816]: info: CoreStateMachine::getQueue Oct 15 20:17:56 volumio1 volumio[816]: info: CorePlayQueue::getQueue Oct 15 20:17:56 volumio1 volumio[816]: info: Listing playlists Oct 15 20:17:56 volumio1 volumio[816]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 15 20:17:56 volumio1 volumio[816]: info: Received Get System Info Oct 15 20:17:56 volumio1 volumio[816]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 15 20:17:56 volumio1 volumio[816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 15 20:17:56 volumio1 volumio[816]: info: Discovery: Getting this device information Oct 15 20:17:56 volumio1 volumio[816]: info: CoreCommandRouter::volumioGetState Oct 15 20:17:56 volumio1 volumio[816]: info: CorePlayQueue::getTrack 17 Oct 15 20:17:56 volumio1 volumio[816]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 15 20:17:56 volumio1 volumio[816]: info: CoreCommandRouter::volumioGetState Oct 15 20:17:56 volumio1 volumio[816]: info: CorePlayQueue::getTrack 17 Oct 15 20:17:56 volumio1 volumio[816]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 15 20:17:56 volumio1 volumio[816]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 15 20:17:56 volumio1 volumio5-onboarding[1053]: time=2026-10-15T20:17:56.924+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%03,192.168.1.80:43080 @ 0x249b320" latency=-704.608763ms timeout=10s endpoint=https://google.com duration=684.149075ms Oct 15 20:17:56 volumio1 volumio[816]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 15 20:17:56 volumio1 volumio[816]: info: FusionDsp - Utah5 Oct 15 20:17:57 volumio1 volumio[816]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json,Soggiorno.json,Utah5.json Oct 15 20:17:57 volumio1 volumio[816]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 15 20:17:58 volumio1 volumio[816]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 15 20:17:58 volumio1 volumio[816]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 15 20:17:58 volumio1 volumio[816]: info: Received Get System Info Oct 15 20:17:58 volumio1 volumio[816]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 15 20:17:58 volumio1 volumio[816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 15 20:17:58 volumio1 volumio[816]: info: Discovery: Getting this device information Oct 15 20:17:58 volumio1 volumio[816]: info: CoreCommandRouter::volumioGetState Oct 15 20:17:58 volumio1 volumio[816]: info: CorePlayQueue::getTrack 17 Oct 15 20:17:58 volumio1 volumio[816]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 15 20:17:59 volumio1 volumio[816]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 15 20:17:59 volumio1 volumio[816]: info: Received Get System Info Oct 15 20:17:59 volumio1 volumio[816]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 15 20:17:59 volumio1 volumio[816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 15 20:17:59 volumio1 volumio[816]: info: Discovery: Getting this device information Oct 15 20:17:59 volumio1 volumio[816]: info: CoreCommandRouter::volumioGetState Oct 15 20:17:59 volumio1 volumio[816]: info: CorePlayQueue::getTrack 17 Oct 15 20:17:59 volumio1 volumio[816]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 15 20:18:06 volumio1 volumio[816]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 15 20:18:08 volumio1 volumio[816]: Searching plugin music_service/mpd Oct 15 20:18:08 volumio1 volumio[816]: info: CoreCommandRouter::executeOnPlugin: mpd , search Oct 15 20:18:08 volumio1 volumio[816]: info: All search sources collected, pushing search results Oct 15 20:18:09 volumio1 volumio[816]: Searching plugin music_service/mpd Oct 15 20:18:09 volumio1 volumio[816]: info: CoreCommandRouter::executeOnPlugin: mpd , search Oct 15 20:18:09 volumio1 volumio[816]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Oct 15 20:18:11 volumio1 volumio[816]: info: All search sources collected, pushing search results Oct 15 20:18:11 volumio1 volumio[816]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Oct 15 20:18:12 volumio1 volumio[816]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Oct 15 20:18:14 volumio1 volumio[816]: Searching plugin music_service/mpd Oct 15 20:18:14 volumio1 volumio[816]: info: CoreCommandRouter::executeOnPlugin: mpd , search Oct 15 20:18:15 volumio1 volumio[816]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 15 20:18:15 volumio1 volumio[816]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 15 20:18:15 volumio1 volumio[816]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 15 20:18:15 volumio1 volumio[816]: info: Received Get System Version Oct 15 20:18:15 volumio1 volumio[816]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 15 20:18:15 volumio1 volumio[816]: info: Received Get System Info Oct 15 20:18:15 volumio1 volumio[816]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 15 20:18:15 volumio1 volumio[816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 15 20:18:15 volumio1 volumio[816]: info: Discovery: Getting this device information Oct 15 20:18:15 volumio1 volumio[816]: info: CoreCommandRouter::volumioGetState Oct 15 20:18:15 volumio1 volumio[816]: info: CorePlayQueue::getTrack 17 Oct 15 20:18:15 volumio1 volumio[816]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 15 20:18:15 volumio1 volumio[816]: Cannot compose Albumart path Oct 15 20:18:15 volumio1 volumio[816]: Cannot compose Albumart path Oct 15 20:18:15 volumio1 volumio[816]: Cannot compose Albumart path Oct 15 20:18:15 volumio1 volumio[816]: Cannot compose Albumart path Oct 15 20:18:15 volumio1 volumio[816]: Cannot compose Albumart path Oct 15 20:18:15 volumio1 volumio[816]: Cannot compose Albumart path Oct 15 20:18:15 volumio1 volumio[816]: Cannot compose Albumart path Oct 15 20:18:15 volumio1 volumio[816]: Cannot compose Albumart path Oct 15 20:18:15 volumio1 volumio[816]: Cannot compose Albumart path Oct 15 20:18:15 volumio1 volumio[816]: info: All search sources collected, pushing search results Oct 15 20:18:19 volumio1 volumio[816]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 15 20:18:19 volumio1 volumio[816]: TypeError: Cannot read property 'slice' of null Oct 15 20:18:19 volumio1 volumio[816]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Oct 15 20:18:19 volumio1 volumio[816]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Oct 15 20:18:19 volumio1 volumio[816]: at Object.onceWrapper (events.js:422:26) Oct 15 20:18:19 volumio1 volumio[816]: at ClientRequest.emit (events.js:315:20) Oct 15 20:18:19 volumio1 volumio[816]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:641:27) Oct 15 20:18:19 volumio1 volumio[816]: at HTTPParser.parserOnHeadersComplete (_http_common.js:126:17) Oct 15 20:18:19 volumio1 volumio[816]: at TLSSocket.socketOnData (_http_client.js:509:22) Oct 15 20:18:19 volumio1 volumio[816]: at TLSSocket.emit (events.js:315:20) Oct 15 20:18:19 volumio1 volumio[816]: at addChunk (internal/streams/readable.js:309:12) Oct 15 20:18:19 volumio1 volumio[816]: at readableAddChunk (internal/streams/readable.js:284:9) Oct 15 20:18:19 volumio1 volumio[816]: at TLSSocket.Readable.push (internal/streams/readable.js:223:10) Oct 15 20:18:19 volumio1 volumio[816]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:188:23) Oct 15 20:18:19 volumio1 volumio[816]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 15 20:18:20 volumio1 sudo[11564]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-10-15 20:17 Oct 15 20:18:20 volumio1 sudo[11564]: 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="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 10:59:40 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="37c6ab864cb114e1344d540995c69f86"