Oct 11 19:56:01 volumio-gilly volumio[1391]: info: Discovery: Restarting Browsing
Oct 11 19:56:06 volumio-gilly volumio[1391]: info: Discovery: Restarting Browsing
Oct 11 19:56:08 volumio-gilly volumio[1391]: error: FusionDsp - Monitor WebSocket error: [object Object]
Oct 11 19:56:08 volumio-gilly volumio[1391]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms
Oct 11 19:56:11 volumio-gilly volumio[1391]: info: Discovery: Restarting Browsing
Oct 11 19:56:16 volumio-gilly volumio[1391]: info: Discovery: Restarting Browsing
Oct 11 19:56:18 volumio-gilly volumio[1391]: warn: FusionDsp - Monitor WebSocket not open, skipping commands
Oct 11 19:56:21 volumio-gilly volumio[1391]: info: Discovery: Restarting Browsing
Oct 11 19:56:26 volumio-gilly volumio[1391]: info: Discovery: Restarting Browsing
Oct 11 19:56:29 volumio-gilly volumio5-onboarding[1660]: time=2026-10-11T19:56:29.264+02:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="00:00:00:00:00:00%02,192.168.1.8:48774 @ 0x262f9b0" latency=804.403924ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE
Oct 11 19:56:29 volumio-gilly volumio5-onboarding[1660]: time=2026-10-11T19:56:29.281+02:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%02,192.168.1.8:48774 @ 0x262f9b0" latency=793.050896ms timeout=10s
Oct 11 19:56:29 volumio-gilly volumio5-onboarding[1660]: time=2026-10-11T19:56:29.396+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%02,192.168.1.8:48774 @ 0x262f9b0" latency=793.050896ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=114.984111ms
Oct 11 19:56:29 volumio-gilly volumio5-onboarding[1660]: time=2026-10-11T19:56:29.396+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%02,192.168.1.8:48774 @ 0x262f9b0" latency=793.050896ms timeout=10s endpoint=http://pushupdates.volumio.org duration=113.86507ms
Oct 11 19:56:29 volumio-gilly volumio5-onboarding[1660]: time=2026-10-11T19:56:29.507+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%02,192.168.1.8:48774 @ 0x262f9b0" latency=793.050896ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=223.848708ms
Oct 11 19:56:29 volumio-gilly volumio5-onboarding[1660]: time=2026-10-11T19:56:29.513+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%02,192.168.1.8:48774 @ 0x262f9b0" latency=793.050896ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=230.346662ms
Oct 11 19:56:29 volumio-gilly volumio5-onboarding[1660]: time=2026-10-11T19:56:29.531+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%02,192.168.1.8:48774 @ 0x262f9b0" latency=793.050896ms timeout=10s endpoint=https://www.googleapis.com duration=250.438296ms
Oct 11 19:56:29 volumio-gilly volumio5-onboarding[1660]: time=2026-10-11T19:56:29.552+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%02,192.168.1.8:48774 @ 0x262f9b0" latency=793.050896ms timeout=10s endpoint=https://functions.volumio.cloud duration=270.243285ms
Oct 11 19:56:29 volumio-gilly volumio5-onboarding[1660]: time=2026-10-11T19:56:29.552+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%02,192.168.1.8:48774 @ 0x262f9b0" latency=793.050896ms timeout=10s endpoint=https://functions.volumio.cloud duration=269.5767ms
Oct 11 19:56:29 volumio-gilly volumio5-onboarding[1660]: time=2026-10-11T19:56:29.571+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%02,192.168.1.8:48774 @ 0x262f9b0" latency=793.050896ms timeout=10s endpoint=https://securetoken.googleapis.com duration=290.229604ms
Oct 11 19:56:29 volumio-gilly volumio5-onboarding[1660]: time=2026-10-11T19:56:29.637+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%02,192.168.1.8:48774 @ 0x262f9b0" latency=793.050896ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=354.963429ms
Oct 11 19:56:29 volumio-gilly volumio5-onboarding[1660]: time=2026-10-11T19:56:29.724+02:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%02,192.168.1.8:48774 @ 0x262f9b0" latency=793.050896ms timeout=10s endpoint=http://cddb.volumio.org duration=442.743442ms error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF"
Oct 11 19:56:29 volumio-gilly volumio5-onboarding[1660]: time=2026-10-11T19:56:29.764+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%02,192.168.1.8:48774 @ 0x262f9b0" latency=793.050896ms timeout=10s endpoint=https://database.volumio.cloud duration=482.535011ms
Oct 11 19:56:29 volumio-gilly volumio5-onboarding[1660]: time=2026-10-11T19:56:29.832+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%02,192.168.1.8:48774 @ 0x262f9b0" latency=793.050896ms timeout=10s endpoint=http://plugins.volumio.org duration=549.691379ms
Oct 11 19:56:30 volumio-gilly volumio5-onboarding[1660]: time=2026-10-11T19:56:30.114+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%02,192.168.1.8:48774 @ 0x262f9b0" latency=793.050896ms timeout=10s endpoint=https://google.com duration=832.918312ms
Oct 11 19:56:31 volumio-gilly volumio[1391]: info: Discovery: Restarting Browsing
Oct 11 19:56:32 volumio-gilly sudo[15829]: volumio : unable to resolve host volumio-gilly: System error
Oct 11 19:56:32 volumio-gilly sudo[15829]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Oct 11 19:56:32 volumio-gilly sudo[15829]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Oct 11 19:56:32 volumio-gilly sudo[15831]: volumio : unable to resolve host volumio-gilly: System error
Oct 11 19:56:32 volumio-gilly sudo[15831]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Oct 11 19:56:32 volumio-gilly sudo[15831]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Oct 11 19:56:32 volumio-gilly sudo[15829]: pam_unix(sudo:session): session closed for user root
Oct 11 19:56:32 volumio-gilly sudo[15831]: pam_unix(sudo:session): session closed for user root
Oct 11 19:56:32 volumio-gilly volumio[1391]: verbose: New Socket.io Connection to 192.168.1.26 from 192.168.1.8 UA: Mozilla/5.0 (Linux; Android 14; M2012K11G Build/UKQ1.231207.002; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/153.0.8010.36 Mobile Safari/537.36 VolumioApp/6.260930.0 Engine version: 3 Transport: polling Total Clients: 9
Oct 11 19:56:34 volumio-gilly sudo[15835]: volumio : unable to resolve host volumio-gilly: System error
Oct 11 19:56:34 volumio-gilly sudo[15835]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Oct 11 19:56:34 volumio-gilly sudo[15837]: volumio : unable to resolve host volumio-gilly: System error
Oct 11 19:56:34 volumio-gilly sudo[15835]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Oct 11 19:56:34 volumio-gilly sudo[15837]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Oct 11 19:56:34 volumio-gilly sudo[15835]: pam_unix(sudo:session): session closed for user root
Oct 11 19:56:34 volumio-gilly sudo[15837]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Oct 11 19:56:34 volumio-gilly sudo[15837]: pam_unix(sudo:session): session closed for user root
Oct 11 19:56:34 volumio-gilly volumio[1391]: verbose: New Socket.io Connection to 192.168.1.26 from 192.168.1.8 UA: Mozilla/5.0 (Linux; Android 14; M2012K11G Build/UKQ1.231207.002; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/153.0.8010.36 Mobile Safari/537.36 VolumioApp/6.260930.0 Engine version: 3 Transport: polling Total Clients: 10
Oct 11 19:56:34 volumio-gilly volumio[1391]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Oct 11 19:56:34 volumio-gilly volumio[1391]: info: CoreCommandRouter::volumioGetVisibleSources
Oct 11 19:56:34 volumio-gilly volumio[1391]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Oct 11 19:56:34 volumio-gilly volumio[1391]: info: CoreCommandRouter::volumioGetState
Oct 11 19:56:34 volumio-gilly volumio[1391]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Oct 11 19:56:34 volumio-gilly volumio[1391]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Oct 11 19:56:34 volumio-gilly volumio[1391]: info: Received Get System Info
Oct 11 19:56:34 volumio-gilly volumio[1391]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Oct 11 19:56:34 volumio-gilly volumio[1391]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Oct 11 19:56:34 volumio-gilly volumio[1391]: info: Discovery: Getting this device information
Oct 11 19:56:34 volumio-gilly volumio[1391]: info: CoreCommandRouter::volumioGetState
Oct 11 19:56:34 volumio-gilly volumio[1391]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Oct 11 19:56:34 volumio-gilly volumio[1391]: info: CoreCommandRouter::volumioGetState
Oct 11 19:56:34 volumio-gilly volumio[1391]: info: Listing playlists
Oct 11 19:56:34 volumio-gilly volumio[1391]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Oct 11 19:56:34 volumio-gilly volumio[1391]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Oct 11 19:56:34 volumio-gilly volumio[1391]: info: CoreCommandRouter::getUIConfigOnPlugin
Oct 11 19:56:34 volumio-gilly volumio[1391]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache
Oct 11 19:56:35 volumio-gilly volumio[1391]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Oct 11 19:56:35 volumio-gilly volumio[1391]: info: Received Get System Info
Oct 11 19:56:35 volumio-gilly volumio[1391]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Oct 11 19:56:35 volumio-gilly volumio[1391]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Oct 11 19:56:35 volumio-gilly volumio[1391]: info: Discovery: Getting this device information
Oct 11 19:56:35 volumio-gilly volumio[1391]: info: CoreCommandRouter::volumioGetState
Oct 11 19:56:35 volumio-gilly volumio[1391]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Oct 11 19:56:36 volumio-gilly volumio[1391]: info: Discovery: Restarting Browsing
Oct 11 19:56:36 volumio-gilly volumio[1391]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard
Oct 11 19:56:36 volumio-gilly volumio[1391]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Oct 11 19:56:36 volumio-gilly volumio[1391]: info: Received Get System Info
Oct 11 19:56:36 volumio-gilly volumio[1391]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Oct 11 19:56:36 volumio-gilly volumio[1391]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Oct 11 19:56:36 volumio-gilly volumio[1391]: info: Discovery: Getting this device information
Oct 11 19:56:36 volumio-gilly volumio[1391]: info: CoreCommandRouter::volumioGetState
Oct 11 19:56:36 volumio-gilly volumio[1391]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Oct 11 19:56:37 volumio-gilly volumio[1391]: info: CoreCommandRouter::volumioStop
Oct 11 19:56:37 volumio-gilly volumio[1391]: info: CoreStateMachine::stop
Oct 11 19:56:37 volumio-gilly volumio[1391]: info: CoreStateMachine::setConsumeUpdateService undefined
Oct 11 19:56:38 volumio-gilly volumio[1391]: warn: FusionDsp - Monitor WebSocket not open, skipping commands
Oct 11 19:56:38 volumio-gilly volumio[1391]: info: CoreCommandRouter::volumioNext
Oct 11 19:56:38 volumio-gilly volumio[1391]: info: CoreStateMachine::next
Oct 11 19:56:38 volumio-gilly volumio[1391]: info: CoreStateMachine::stop
Oct 11 19:56:38 volumio-gilly volumio[1391]: info: CoreStateMachine::setConsumeUpdateService undefined
Oct 11 19:56:38 volumio-gilly volumio[1391]: info: CoreStateMachine::play index undefined
Oct 11 19:56:38 volumio-gilly volumio[1391]: info: CoreStateMachine::setConsumeUpdateService undefined
Oct 11 19:56:38 volumio-gilly volumio[1391]: info: CorePlayQueue::getTrack 1
Oct 11 19:56:38 volumio-gilly volumio[1391]: error: FusionDsp - Monitor WebSocket error: [object Object]
Oct 11 19:56:38 volumio-gilly volumio[1391]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms
Oct 11 19:56:41 volumio-gilly volumio[1391]: info: Discovery: Restarting Browsing
Oct 11 19:56:41 volumio-gilly volumio[1391]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Oct 11 19:56:41 volumio-gilly volumio[1391]: info: CoreCommandRouter::getUIConfigOnPlugin
Oct 11 19:56:41 volumio-gilly volumio[1391]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
Oct 11 19:56:44 volumio-gilly volumio[1391]: info: CoreCommandRouter::getUIConfigOnPlugin
Oct 11 19:56:44 volumio-gilly volumio[1391]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
Oct 11 19:56:46 volumio-gilly volumio[1391]: info: Discovery: Restarting Browsing
Oct 11 19:56:48 volumio-gilly volumio[1391]: info: CALLMETHOD: audio_interface mpdoutput ehttpstream
Oct 11 19:56:48 volumio-gilly volumio[1391]: info: CoreCommandRouter::executeOnPlugin: mpdoutput , ehttpstream
Oct 11 19:56:48 volumio-gilly volumio[1391]: info: mpdhttpout ---TRUE
Oct 11 19:56:48 volumio-gilly volumio[1391]: info: CoreCommandRouter::volumioGetState
Oct 11 19:56:48 volumio-gilly volumio[1391]: info: CorePlayQueue::getTrack 0
Oct 11 19:56:48 volumio-gilly volumio[1391]: info: CoreCommandRouter::volumioPause
Oct 11 19:56:48 volumio-gilly volumio[1391]: info: CoreStateMachine::pause
Oct 11 19:56:48 volumio-gilly volumio[1391]: info: mpdhttpout --- Volumio set on pause
Oct 11 19:56:48 volumio-gilly volumio[1391]: info: mpdhttpout ---Configuration successfully added to mpd.conf.
Oct 11 19:56:49 volumio-gilly volumio[1391]: info: CoreCommandRouter::getUIConfigOnPlugin
Oct 11 19:56:50 volumio-gilly volumio[1391]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd
Oct 11 19:56:50 volumio-gilly volumio[1391]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Oct 11 19:56:50 volumio-gilly volumio[1391]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined
Oct 11 19:56:50 volumio-gilly volumio[1391]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates
Oct 11 19:56:50 volumio-gilly volumio[1391]: info: Received Get System Version
Oct 11 19:56:50 volumio-gilly volumio[1391]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Oct 11 19:56:50 volumio-gilly volumio[1391]: info: Received Get System Info
Oct 11 19:56:50 volumio-gilly volumio[1391]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Oct 11 19:56:50 volumio-gilly volumio[1391]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Oct 11 19:56:50 volumio-gilly volumio[1391]: info: Discovery: Getting this device information
Oct 11 19:56:50 volumio-gilly volumio[1391]: info: CoreCommandRouter::volumioGetState
Oct 11 19:56:50 volumio-gilly volumio[1391]: info: CorePlayQueue::getTrack 0
Oct 11 19:56:50 volumio-gilly volumio[1391]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Oct 11 19:56:51 volumio-gilly volumio[1391]: info: Discovery: Restarting Browsing
Oct 11 19:56:52 volumio-gilly sudo[15874]: volumio : unable to resolve host volumio-gilly: System error
Oct 11 19:56:52 volumio-gilly sudo[15874]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Oct 11 19:56:52 volumio-gilly sudo[15874]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Oct 11 19:56:52 volumio-gilly systemd[1]: Stopping mpd.service - Music Player Daemon...
Oct 11 19:56:52 volumio-gilly systemd[1]: mpd.service: Deactivated successfully.
Oct 11 19:56:52 volumio-gilly systemd[1]: Stopped mpd.service - Music Player Daemon.
Oct 11 19:56:52 volumio-gilly systemd[1]: mpd.service: Consumed 25.592s CPU time.
Oct 11 19:56:52 volumio-gilly systemd[1]: mpd.socket: Deactivated successfully.
Oct 11 19:56:52 volumio-gilly systemd[1]: Closed mpd.socket - Music Player Daemon Socket.
Oct 11 19:56:52 volumio-gilly systemd[1]: Stopping mpd.socket - Music Player Daemon Socket...
Oct 11 19:56:52 volumio-gilly systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Oct 11 19:56:52 volumio-gilly systemd[1]: Starting mpd.service - Music Player Daemon...
Oct 11 19:56:54 volumio-gilly sudo[15877]: root : unable to resolve host volumio-gilly: System error
Oct 11 19:56:54 volumio-gilly sudo[15877]: sudo: unable to resolve host volumio-gilly: System error
Oct 11 19:56:54 volumio-gilly sudo[15877]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Oct 11 19:56:54 volumio-gilly sudo[15877]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Oct 11 19:56:54 volumio-gilly sudo[15877]: pam_unix(sudo:session): session closed for user root
Oct 11 19:56:55 volumio-gilly volumio[1391]: info: CALLMETHOD: audio_interface mpdoutput updateConfig [object Object]
Oct 11 19:56:55 volumio-gilly volumio[1391]: info: CoreCommandRouter::executeOnPlugin: mpdoutput , updateConfig
Oct 11 19:56:55 volumio-gilly volumio[1391]: info: mpdhttpout ---Received data for http: {"bitrate":{"label":"320","value":"320"},"buffer_time":"5000","encoder":{"label":"lame","value":"lame"},"format":{"label":"44100:16:2","value":"44100:16:2"},"httpadd":false,"max_clients":"5","outburst_time":"2000","portn":"8000","servername":"localhost"}
Oct 11 19:56:55 volumio-gilly volumio[1391]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf.
Oct 11 19:56:55 volumio-gilly volumio[1391]: info: CoreCommandRouter::volumioGetState
Oct 11 19:56:55 volumio-gilly volumio[1391]: info: CorePlayQueue::getTrack 0
Oct 11 19:56:55 volumio-gilly volumio[1391]: info: CoreCommandRouter::volumioPause
Oct 11 19:56:55 volumio-gilly volumio[1391]: info: CoreStateMachine::pause
Oct 11 19:56:55 volumio-gilly volumio[1391]: info: mpdhttpout --- Volumio set on pause
Oct 11 19:56:55 volumio-gilly mpd[15880]: 2026-10-11T19:56:55 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Oct 11 19:56:55 volumio-gilly systemd[1]: Started mpd.service - Music Player Daemon.
Oct 11 19:56:55 volumio-gilly sudo[15874]: pam_unix(sudo:session): session closed for user root
Oct 11 19:56:55 volumio-gilly volumio[1391]: error: updateQueue error: null
Oct 11 19:56:56 volumio-gilly volumio[1391]: info: Discovery: Restarting Browsing
Oct 11 19:56:57 volumio-gilly volumio[1391]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd
Oct 11 19:56:57 volumio-gilly volumio[1391]: info: CALLMETHOD: audio_interface mpdoutput eicestream
Oct 11 19:56:57 volumio-gilly volumio[1391]: info: CoreCommandRouter::executeOnPlugin: mpdoutput , eicestream
Oct 11 19:56:57 volumio-gilly volumio[1391]: info: mpdhttpout ---TRUE
Oct 11 19:56:57 volumio-gilly volumio[1391]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf.
Oct 11 19:56:57 volumio-gilly volumio[1391]: info: CoreCommandRouter::volumioGetState
Oct 11 19:56:57 volumio-gilly volumio[1391]: info: CorePlayQueue::getTrack 0
Oct 11 19:56:57 volumio-gilly volumio[1391]: info: CoreCommandRouter::volumioPause
Oct 11 19:56:57 volumio-gilly volumio[1391]: info: CoreStateMachine::pause
Oct 11 19:56:57 volumio-gilly volumio[1391]: info: mpdhttpout --- Volumio set on pause
Oct 11 19:56:58 volumio-gilly volumio[1391]: warn: FusionDsp - Monitor WebSocket not open, skipping commands
Oct 11 19:56:59 volumio-gilly volumio[1391]: info: CoreCommandRouter::getUIConfigOnPlugin
Oct 11 19:56:59 volumio-gilly sudo[15884]: volumio : unable to resolve host volumio-gilly: System error
Oct 11 19:56:59 volumio-gilly sudo[15884]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Oct 11 19:56:59 volumio-gilly sudo[15884]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Oct 11 19:56:59 volumio-gilly systemd[1]: Stopping mpd.service - Music Player Daemon...
Oct 11 19:56:59 volumio-gilly systemd[1]: mpd.service: Deactivated successfully.
Oct 11 19:56:59 volumio-gilly systemd[1]: Stopped mpd.service - Music Player Daemon.
Oct 11 19:56:59 volumio-gilly systemd[1]: mpd.service: Consumed 1.323s CPU time.
Oct 11 19:56:59 volumio-gilly systemd[1]: mpd.socket: Deactivated successfully.
Oct 11 19:56:59 volumio-gilly systemd[1]: Closed mpd.socket - Music Player Daemon Socket.
Oct 11 19:56:59 volumio-gilly systemd[1]: Stopping mpd.socket - Music Player Daemon Socket...
Oct 11 19:56:59 volumio-gilly systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Oct 11 19:56:59 volumio-gilly systemd[1]: Starting mpd.service - Music Player Daemon...
Oct 11 19:57:00 volumio-gilly volumio[1391]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd
Oct 11 19:57:01 volumio-gilly volumio[1391]: info: Discovery: Restarting Browsing
Oct 11 19:57:01 volumio-gilly sudo[15889]: root : unable to resolve host volumio-gilly: System error
Oct 11 19:57:01 volumio-gilly sudo[15889]: sudo: unable to resolve host volumio-gilly: System error
Oct 11 19:57:01 volumio-gilly sudo[15889]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Oct 11 19:57:01 volumio-gilly sudo[15889]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Oct 11 19:57:01 volumio-gilly sudo[15889]: pam_unix(sudo:session): session closed for user root
Oct 11 19:57:02 volumio-gilly sudo[15906]: volumio : unable to resolve host volumio-gilly: System error
Oct 11 19:57:02 volumio-gilly sudo[15906]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Oct 11 19:57:02 volumio-gilly sudo[15906]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Oct 11 19:57:02 volumio-gilly systemd[1]: mpd.service: Deactivated successfully.
Oct 11 19:57:02 volumio-gilly systemd[1]: Stopped mpd.service - Music Player Daemon.
Oct 11 19:57:02 volumio-gilly systemd[1]: mpd.socket: Deactivated successfully.
Oct 11 19:57:02 volumio-gilly systemd[1]: Closed mpd.socket - Music Player Daemon Socket.
Oct 11 19:57:02 volumio-gilly systemd[1]: Stopping mpd.socket - Music Player Daemon Socket...
Oct 11 19:57:02 volumio-gilly systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Oct 11 19:57:02 volumio-gilly systemd[1]: Starting mpd.service - Music Player Daemon...
Oct 11 19:57:03 volumio-gilly volumio[1391]: info: CALLMETHOD: audio_interface mpdoutput updateConfigIce [object Object]
Oct 11 19:57:03 volumio-gilly volumio[1391]: info: CoreCommandRouter::executeOnPlugin: mpdoutput , updateConfigIce
Oct 11 19:57:03 volumio-gilly volumio[1391]: info: mpdhttpout ---Received data for icecast: {"iceadd":false,"icebitrate":{"label":"320","value":"320"},"icedescription":"My music stream","iceencoder":{"label":"vorbis","value":"vorbis"},"iceformat":{"label":"44100:16:2","value":"44100:16:2"},"icegenre":"various","icemountp":"/volumio.ogg","icepassword":"hackme","iceprotocol":{"label":"icecast2","value":"icecast2"},"icepublic":{"label":"no","value":"no"},"icepublishport":"8015","iceservername":"ice.example.com","icestreamname":"My Ice stream","iceurl":"","iceuser":"source"}
Oct 11 19:57:03 volumio-gilly volumio[1391]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf.
Oct 11 19:57:03 volumio-gilly volumio[1391]: info: CoreCommandRouter::volumioGetState
Oct 11 19:57:03 volumio-gilly volumio[1391]: info: CorePlayQueue::getTrack 0
Oct 11 19:57:03 volumio-gilly volumio[1391]: info: CoreCommandRouter::volumioPause
Oct 11 19:57:03 volumio-gilly volumio[1391]: info: CoreStateMachine::pause
Oct 11 19:57:03 volumio-gilly volumio[1391]: info: mpdhttpout --- Volumio set on pause
Oct 11 19:57:04 volumio-gilly sudo[15911]: root : unable to resolve host volumio-gilly: System error
Oct 11 19:57:04 volumio-gilly sudo[15911]: sudo: unable to resolve host volumio-gilly: System error
Oct 11 19:57:04 volumio-gilly sudo[15911]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Oct 11 19:57:04 volumio-gilly sudo[15911]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Oct 11 19:57:04 volumio-gilly sudo[15911]: pam_unix(sudo:session): session closed for user root
Oct 11 19:57:04 volumio-gilly volumio[1391]: info: CALLMETHOD: audio_interface mpdoutput updateConfigIce [object Object]
Oct 11 19:57:04 volumio-gilly volumio[1391]: info: CoreCommandRouter::executeOnPlugin: mpdoutput , updateConfigIce
Oct 11 19:57:04 volumio-gilly volumio[1391]: info: mpdhttpout ---Received data for icecast: {"iceadd":false,"icebitrate":{"label":"320","value":"320"},"icedescription":"My music stream","iceencoder":{"label":"vorbis","value":"vorbis"},"iceformat":{"label":"44100:16:2","value":"44100:16:2"},"icegenre":"various","icemountp":"/volumio.ogg","icepassword":"hackme","iceprotocol":{"label":"icecast2","value":"icecast2"},"icepublic":{"label":"no","value":"no"},"icepublishport":"8015","iceservername":"ice.example.com","icestreamname":"My Ice stream","iceurl":"","iceuser":"source"}
Oct 11 19:57:04 volumio-gilly volumio[1391]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf.
Oct 11 19:57:04 volumio-gilly volumio[1391]: info: CoreCommandRouter::volumioGetState
Oct 11 19:57:04 volumio-gilly volumio[1391]: info: CorePlayQueue::getTrack 0
Oct 11 19:57:04 volumio-gilly volumio[1391]: info: CoreCommandRouter::volumioPause
Oct 11 19:57:04 volumio-gilly volumio[1391]: info: CoreStateMachine::pause
Oct 11 19:57:04 volumio-gilly volumio[1391]: info: mpdhttpout --- Volumio set on pause
Oct 11 19:57:05 volumio-gilly mpd[15913]: 2026-10-11T19:57:05 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Oct 11 19:57:05 volumio-gilly systemd[1]: Started mpd.service - Music Player Daemon.
Oct 11 19:57:05 volumio-gilly sudo[15906]: pam_unix(sudo:session): session closed for user root
Oct 11 19:57:05 volumio-gilly sudo[15884]: pam_unix(sudo:session): session closed for user root
Oct 11 19:57:05 volumio-gilly volumio[1391]: error: updateQueue error: null
Oct 11 19:57:05 volumio-gilly volumio[1391]: error: updateQueue error: null
Oct 11 19:57:05 volumio-gilly volumio[1391]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd
Oct 11 19:57:06 volumio-gilly volumio[1391]: info: Discovery: Restarting Browsing
Oct 11 19:57:06 volumio-gilly volumio[1391]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd
Oct 11 19:57:07 volumio-gilly sudo[15917]: volumio : unable to resolve host volumio-gilly: System error
Oct 11 19:57:07 volumio-gilly sudo[15917]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Oct 11 19:57:07 volumio-gilly sudo[15917]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Oct 11 19:57:07 volumio-gilly systemd[1]: Stopping mpd.service - Music Player Daemon...
Oct 11 19:57:07 volumio-gilly systemd[1]: mpd.service: Deactivated successfully.
Oct 11 19:57:07 volumio-gilly systemd[1]: Stopped mpd.service - Music Player Daemon.
Oct 11 19:57:07 volumio-gilly systemd[1]: mpd.service: Consumed 1.306s CPU time.
Oct 11 19:57:07 volumio-gilly systemd[1]: mpd.socket: Deactivated successfully.
Oct 11 19:57:07 volumio-gilly systemd[1]: Closed mpd.socket - Music Player Daemon Socket.
Oct 11 19:57:07 volumio-gilly systemd[1]: Stopping mpd.socket - Music Player Daemon Socket...
Oct 11 19:57:07 volumio-gilly systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Oct 11 19:57:07 volumio-gilly systemd[1]: Starting mpd.service - Music Player Daemon...
Oct 11 19:57:08 volumio-gilly sudo[15919]: volumio : unable to resolve host volumio-gilly: System error
Oct 11 19:57:08 volumio-gilly sudo[15919]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Oct 11 19:57:08 volumio-gilly sudo[15919]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Oct 11 19:57:08 volumio-gilly volumio[1391]: error: FusionDsp - Monitor WebSocket error: [object Object]
Oct 11 19:57:08 volumio-gilly volumio[1391]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms
Oct 11 19:57:09 volumio-gilly sudo[15922]: root : unable to resolve host volumio-gilly: System error
Oct 11 19:57:09 volumio-gilly sudo[15922]: sudo: unable to resolve host volumio-gilly: System error
Oct 11 19:57:09 volumio-gilly sudo[15922]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Oct 11 19:57:09 volumio-gilly sudo[15922]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Oct 11 19:57:09 volumio-gilly sudo[15922]: pam_unix(sudo:session): session closed for user root
Oct 11 19:57:09 volumio-gilly systemd[1]: mpd.service: Deactivated successfully.
Oct 11 19:57:09 volumio-gilly systemd[1]: Stopped mpd.service - Music Player Daemon.
Oct 11 19:57:09 volumio-gilly systemd[1]: mpd.socket: Deactivated successfully.
Oct 11 19:57:09 volumio-gilly systemd[1]: Closed mpd.socket - Music Player Daemon Socket.
Oct 11 19:57:09 volumio-gilly systemd[1]: Stopping mpd.socket - Music Player Daemon Socket...
Oct 11 19:57:09 volumio-gilly systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Oct 11 19:57:09 volumio-gilly systemd[1]: Starting mpd.service - Music Player Daemon...
Oct 11 19:57:11 volumio-gilly volumio[1391]: info: Discovery: Restarting Browsing
Oct 11 19:57:11 volumio-gilly sudo[15940]: root : unable to resolve host volumio-gilly: System error
Oct 11 19:57:11 volumio-gilly sudo[15940]: sudo: unable to resolve host volumio-gilly: System error
Oct 11 19:57:11 volumio-gilly sudo[15940]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Oct 11 19:57:11 volumio-gilly sudo[15940]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Oct 11 19:57:11 volumio-gilly sudo[15940]: pam_unix(sudo:session): session closed for user root
Oct 11 19:57:13 volumio-gilly mpd[15942]: 2026-10-11T19:57:13 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Oct 11 19:57:13 volumio-gilly systemd[1]: Started mpd.service - Music Player Daemon.
Oct 11 19:57:13 volumio-gilly sudo[15917]: pam_unix(sudo:session): session closed for user root
Oct 11 19:57:13 volumio-gilly sudo[15919]: pam_unix(sudo:session): session closed for user root
Oct 11 19:57:13 volumio-gilly volumio[1391]: error: updateQueue error: null
Oct 11 19:57:13 volumio-gilly volumio[1391]: error: updateQueue error: null
Oct 11 19:57:14 volumio-gilly volumio[1391]: info: CoreCommandRouter::volumioGetQueue
Oct 11 19:57:14 volumio-gilly volumio[1391]: info: CoreStateMachine::getQueue
Oct 11 19:57:14 volumio-gilly volumio[1391]: info: CorePlayQueue::getQueue
Oct 11 19:57:16 volumio-gilly volumio[1391]: info: Discovery: Restarting Browsing
Oct 11 19:57:18 volumio-gilly volumio[1391]: warn: FusionDsp - Monitor WebSocket not open, skipping commands
Oct 11 19:57:21 volumio-gilly volumio[1391]: info: Discovery: Restarting Browsing
Oct 11 19:57:21 volumio-gilly volumio[1391]: info: CoreCommandRouter::executeOnPlugin: rp2 , handleBrowseUri
Oct 11 19:57:21 volumio-gilly volumio[1391]: info: Preload queue cleared
Oct 11 19:57:26 volumio-gilly volumio[1391]: info: Discovery: Restarting Browsing
Oct 11 19:57:29 volumio-gilly volumio[1391]: info: CoreCommandRouter::executeOnPlugin: motherearthradio , handleBrowseUri
Oct 11 19:57:29 volumio-gilly volumio[1391]: info: Preload queue cleared
Oct 11 19:57:30 volumio-gilly volumio[1391]: info: Preload queue cleared
Oct 11 19:57:30 volumio-gilly volumio[1391]: info: CoreCommandRouter::volumioReplaceandPlayItems
Oct 11 19:57:30 volumio-gilly volumio[1391]: info: CoreStateMachine::ClearQueue
Oct 11 19:57:30 volumio-gilly volumio[1391]: info: CoreStateMachine::stop
Oct 11 19:57:30 volumio-gilly volumio[1391]: info: CoreStateMachine::setConsumeUpdateService undefined
Oct 11 19:57:30 volumio-gilly volumio[1391]: info: CorePlayQueue::clearPlayQueue
Oct 11 19:57:30 volumio-gilly volumio[1391]: info: CorePlayQueue::saveQueue
Oct 11 19:57:30 volumio-gilly volumio[1391]: info: CoreCommandRouter::volumioPushQueue
Oct 11 19:57:30 volumio-gilly volumio[1391]: info: CoreStateMachine::addQueueItems
Oct 11 19:57:30 volumio-gilly volumio[1391]: info: CorePlayQueue::addQueueItems
Oct 11 19:57:30 volumio-gilly volumio[1391]: info: Preload queue cleared
Oct 11 19:57:30 volumio-gilly volumio[1391]: info: Adding Item to queue: motherearthradio/radio/flac192
Oct 11 19:57:30 volumio-gilly volumio[1391]: info: Using cached record of: motherearthradio/radio/flac192
Oct 11 19:57:30 volumio-gilly volumio[1391]: info: CoreCommandRouter::volumioPushQueue
Oct 11 19:57:30 volumio-gilly volumio[1391]: info: CorePlayQueue::saveQueue
Oct 11 19:57:30 volumio-gilly volumio[1391]: info: CoreStateMachine::updateTrackBlock
Oct 11 19:57:30 volumio-gilly volumio[1391]: info: CorePlayQueue::getTrackBlock
Oct 11 19:57:30 volumio-gilly volumio[1391]: info: CoreCommandRouter::volumioPlay
Oct 11 19:57:30 volumio-gilly volumio[1391]: info: CoreStateMachine::play index 0
Oct 11 19:57:30 volumio-gilly volumio[1391]: info: CoreStateMachine::setConsumeUpdateService undefined
Oct 11 19:57:30 volumio-gilly volumio[1391]: info: CoreStateMachine::stop
Oct 11 19:57:30 volumio-gilly volumio[1391]: info: CoreStateMachine::setConsumeUpdateService undefined
Oct 11 19:57:30 volumio-gilly volumio[1391]: info: CoreStateMachine::play index undefined
Oct 11 19:57:30 volumio-gilly volumio[1391]: info: CoreStateMachine::setConsumeUpdateService undefined
Oct 11 19:57:30 volumio-gilly volumio[1391]: info: CorePlayQueue::getTrack 0
Oct 11 19:57:30 volumio-gilly volumio[1391]: info: CoreStateMachine::startPlaybackTimer
Oct 11 19:57:30 volumio-gilly volumio[1391]: info: CorePlayQueue::getTrack 0
Oct 11 19:57:30 volumio-gilly volumio[1391]: info: [MER] ▶️ Playing: https://stream.motherearthradio.de/listen/motherearth/motherearth
Oct 11 19:57:30 volumio-gilly volumio[1391]: info: [MER] 🔌 Starting SSE for Radio
Oct 11 19:57:30 volumio-gilly volumio[1391]: verbose: ControllerMpd::sendMpdCommand stop
Oct 11 19:57:30 volumio-gilly volumio[1391]: info: sendMpdCommand stop took 3 milliseconds
Oct 11 19:57:30 volumio-gilly volumio[1391]: verbose: ControllerMpd::sendMpdCommand clear
Oct 11 19:57:30 volumio-gilly volumio[1391]: info:
Oct 11 19:57:30 volumio-gilly volumio[1391]: ---------------------------- MPD announces system playlist update
Oct 11 19:57:30 volumio-gilly volumio[1391]: info: Ignoring MPD Status Update
Oct 11 19:57:30 volumio-gilly volumio[1391]: info: sendMpdCommand clear took 1 milliseconds
Oct 11 19:57:30 volumio-gilly volumio[1391]: verbose: ControllerMpd::sendMpdCommand add "https://stream.motherearthradio.de/listen/motherearth/motherearth"
Oct 11 19:57:30 volumio-gilly volumio[1391]: info:
Oct 11 19:57:30 volumio-gilly volumio[1391]: ---------------------------- MPD announces system playlist update
Oct 11 19:57:30 volumio-gilly volumio[1391]: info: Ignoring MPD Status Update
Oct 11 19:57:30 volumio-gilly volumio[1391]: error: updateQueue error: null
Oct 11 19:57:30 volumio-gilly volumio[1391]: info:
Oct 11 19:57:30 volumio-gilly volumio[1391]: ---------------------------- MPD announces system playlist update
Oct 11 19:57:30 volumio-gilly volumio[1391]: info: Ignoring MPD Status Update
Oct 11 19:57:30 volumio-gilly volumio[1391]: info: ------------------------------ 3ms
Oct 11 19:57:30 volumio-gilly volumio[1391]: info: sendMpdCommand add "https://stream.motherearthradio.de/listen/motherearth/motherearth" took 2 milliseconds
Oct 11 19:57:30 volumio-gilly volumio[1391]: verbose: ControllerMpd::sendMpdCommand play
Oct 11 19:57:30 volumio-gilly volumio[1391]: info: ------------------------------ 4ms
Oct 11 19:57:30 volumio-gilly volumio[1391]: info: ------------------------------ 4ms
Oct 11 19:57:30 volumio-gilly volumio[1391]: info:
Oct 11 19:57:30 volumio-gilly volumio[1391]: ---------------------------- MPD announces system playlist update
Oct 11 19:57:30 volumio-gilly volumio[1391]: info: Ignoring MPD Status Update
Oct 11 19:57:30 volumio-gilly volumio[1391]: info: sendMpdCommand play took 7 milliseconds
Oct 11 19:57:30 volumio-gilly volumio[1391]: info: ------------------------------ 10ms
Oct 11 19:57:30 volumio-gilly volumio[1391]: info: CoreCommandRouter::servicePushState
Oct 11 19:57:30 volumio-gilly volumio[1391]: info: CorePlayQueue::getTrack 0
Oct 11 19:57:30 volumio-gilly volumio[1391]: verbose: STATE SERVICE {"status":"play","service":"motherearthradio","type":"webradio","trackType":"flac","radioType":"mer","title":"Connecting...","artist":"Mother Earth Radio","album":"Radio · FLAC 192kHz/24bit Stereo","albumart":"/albumart?sourceicon=music_service/motherearthradio/mer-logo-cube-bold-1x 512.png","uri":"motherearthradio/radio/flac192","streaming":true,"disableUiControls":true,"samplerate":"192 kHz","bitdepth":"24 bit","duration":0,"seek":0}
Oct 11 19:57:30 volumio-gilly volumio[1391]: verbose: CURRENT POSITION 0
Oct 11 19:57:30 volumio-gilly volumio[1391]: info: CoreStateMachine::syncState stateService play
Oct 11 19:57:30 volumio-gilly volumio[1391]: info: CoreStateMachine::syncState currentStatus stop
Oct 11 19:57:30 volumio-gilly volumio[1391]: error: [MER] SSE connection failed: 403
Oct 11 19:57:30 volumio-gilly volumio[1391]: info: [MER] SSE: Reconnecting in 3000ms
Oct 11 19:57:31 volumio-gilly volumio[1391]: info: Discovery: Restarting Browsing
Oct 11 19:57:31 volumio-gilly volumio[1391]: info:
Oct 11 19:57:31 volumio-gilly volumio[1391]: ---------------------------- MPD announces state update: player
Oct 11 19:57:31 volumio-gilly volumio[1391]: info: ControllerMpd::getState
Oct 11 19:57:31 volumio-gilly volumio[1391]: verbose: ControllerMpd::sendMpdCommand status
Oct 11 19:57:31 volumio-gilly volumio[1391]: info:
Oct 11 19:57:31 volumio-gilly volumio[1391]: ---------------------------- MPD announces state update: player
Oct 11 19:57:31 volumio-gilly volumio[1391]: info: ControllerMpd::getState
Oct 11 19:57:31 volumio-gilly volumio[1391]: verbose: ControllerMpd::sendMpdCommand status
Oct 11 19:57:31 volumio-gilly volumio[1391]: info:
Oct 11 19:57:31 volumio-gilly volumio[1391]: ---------------------------- MPD announces state update: player
Oct 11 19:57:31 volumio-gilly volumio[1391]: info: ControllerMpd::getState
Oct 11 19:57:31 volumio-gilly volumio[1391]: verbose: ControllerMpd::sendMpdCommand status
Oct 11 19:57:31 volumio-gilly volumio[1391]: error: MPD returned error for command status: Failed to decode "https://stream.motherearthradio.de/listen/motherearth/motherearth"; got HTTP status 403
Oct 11 19:57:31 volumio-gilly volumio[1391]: verbose: ControllerMpd::sendMpdCommand clearerror
Oct 11 19:57:31 volumio-gilly volumio[1391]: info: sendMpdCommand status took 14 milliseconds
Oct 11 19:57:31 volumio-gilly volumio[1391]: verbose: ControllerMpd::parseState
Oct 11 19:57:31 volumio-gilly volumio[1391]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Oct 11 19:57:31 volumio-gilly volumio[1391]: info:
Oct 11 19:57:31 volumio-gilly volumio[1391]: ---------------------------- MPD announces state update: player
Oct 11 19:57:31 volumio-gilly volumio[1391]: info: ControllerMpd::getState
Oct 11 19:57:31 volumio-gilly volumio[1391]: verbose: ControllerMpd::sendMpdCommand status
Oct 11 19:57:31 volumio-gilly volumio[1391]: error: MPD returned error for command status: Failed to decode "https://stream.motherearthradio.de/listen/motherearth/motherearth"; got HTTP status 403
Oct 11 19:57:31 volumio-gilly volumio[1391]: verbose: ControllerMpd::sendMpdCommand clearerror
Oct 11 19:57:31 volumio-gilly volumio[1391]: info: sendMpdCommand status took 19 milliseconds
Oct 11 19:57:31 volumio-gilly volumio[1391]: error: MPD returned error for command status: Failed to decode "https://stream.motherearthradio.de/listen/motherearth/motherearth"; got HTTP status 403
Oct 11 19:57:31 volumio-gilly volumio[1391]: verbose: ControllerMpd::sendMpdCommand clearerror
Oct 11 19:57:31 volumio-gilly volumio[1391]: info: sendMpdCommand status took 20 milliseconds
Oct 11 19:57:31 volumio-gilly volumio[1391]: info: sendMpdCommand clearerror took 11 milliseconds
Oct 11 19:57:31 volumio-gilly volumio[1391]: verbose: ControllerMpd::parseState
Oct 11 19:57:31 volumio-gilly volumio[1391]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Oct 11 19:57:31 volumio-gilly volumio[1391]: verbose: ControllerMpd::parseState
Oct 11 19:57:31 volumio-gilly volumio[1391]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Oct 11 19:57:31 volumio-gilly volumio[1391]: info: sendMpdCommand playlistinfo took 19 milliseconds
Oct 11 19:57:31 volumio-gilly volumio[1391]: info: sendMpdCommand status took 19 milliseconds
Oct 11 19:57:31 volumio-gilly volumio[1391]: info: sendMpdCommand clearerror took 19 milliseconds
Oct 11 19:57:31 volumio-gilly volumio[1391]: info: sendMpdCommand clearerror took 18 milliseconds
Oct 11 19:57:31 volumio-gilly volumio[1391]: info: sendMpdCommand playlistinfo took 14 milliseconds
Oct 11 19:57:31 volumio-gilly volumio[1391]: info: sendMpdCommand playlistinfo took 13 milliseconds
Oct 11 19:57:31 volumio-gilly volumio[1391]: verbose: ControllerMpd::parseTrackInfo
Oct 11 19:57:31 volumio-gilly volumio[1391]: verbose: ControllerMpd::parseState
Oct 11 19:57:31 volumio-gilly volumio[1391]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Oct 11 19:57:31 volumio-gilly volumio[1391]: verbose: ControllerMpd::parseTrackInfo
Oct 11 19:57:31 volumio-gilly volumio[1391]: verbose: ControllerMpd::parseTrackInfo
Oct 11 19:57:31 volumio-gilly volumio[1391]: info: ControllerMpd::pushState
Oct 11 19:57:31 volumio-gilly volumio[1391]: info: CoreCommandRouter::servicePushState
Oct 11 19:57:31 volumio-gilly volumio[1391]: info: CoreStateMachine::pushState
Oct 11 19:57:31 volumio-gilly volumio[1391]: info: CorePlayQueue::getTrack 0
Oct 11 19:57:31 volumio-gilly volumio[1391]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 11 19:57:31 volumio-gilly volumio[1391]: info: CoreCommandRouter::volumioPushState
Oct 11 19:57:31 volumio-gilly volumio[1391]: info: CorePlayQueue::getTrack 0
Oct 11 19:57:31 volumio-gilly volumio[1391]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd
Oct 11 19:57:31 volumio-gilly volumio[1391]: info: ControllerMpd::pushState
Oct 11 19:57:31 volumio-gilly volumio[1391]: info: CoreCommandRouter::servicePushState
Oct 11 19:57:31 volumio-gilly volumio[1391]: info: CoreStateMachine::pushState
Oct 11 19:57:31 volumio-gilly volumio[1391]: info: CorePlayQueue::getTrack 0
Oct 11 19:57:31 volumio-gilly volumio[1391]: info: CoreCommandRouter::volumioPushState
Oct 11 19:57:31 volumio-gilly volumio[1391]: info: CorePlayQueue::getTrack 0
Oct 11 19:57:31 volumio-gilly volumio[1391]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd
Oct 11 19:57:31 volumio-gilly volumio[1391]: info: ControllerMpd::pushState
Oct 11 19:57:31 volumio-gilly volumio[1391]: info: CoreCommandRouter::servicePushState
Oct 11 19:57:31 volumio-gilly volumio[1391]: info: CoreStateMachine::pushState
Oct 11 19:57:31 volumio-gilly volumio[1391]: info: CorePlayQueue::getTrack 0
Oct 11 19:57:31 volumio-gilly volumio[1391]: info: CoreCommandRouter::volumioPushState
Oct 11 19:57:31 volumio-gilly volumio[1391]: info: CorePlayQueue::getTrack 0
Oct 11 19:57:31 volumio-gilly volumio[1391]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd
Oct 11 19:57:31 volumio-gilly volumio5-onboarding[1660]: time=2026-10-11T19:57:31.368+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.8:48774 @ 0x22f0030" state=STATUS_PLAYING positionMs=359 volume=46
Oct 11 19:57:31 volumio-gilly volumio5-onboarding[1660]: time=2026-10-11T19:57:31.369+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.8:48774 @ 0x22f0030" state=STATUS_PLAYING positionMs=359 volume=46
Oct 11 19:57:31 volumio-gilly volumio5-onboarding[1660]: time=2026-10-11T19:57:31.369+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02,192.168.1.8:48774 @ 0x262f9b0" state=STATUS_PLAYING positionMs=359 volume=46
Oct 11 19:57:31 volumio-gilly volumio5-onboarding[1660]: time=2026-10-11T19:57:31.368+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.8:48774 @ 0x22f0030" state=STATUS_PLAYING positionMs=359 volume=46
Oct 11 19:57:31 volumio-gilly volumio5-onboarding[1660]: time=2026-10-11T19:57:31.369+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02,192.168.1.8:48774 @ 0x262f9b0" state=STATUS_PLAYING positionMs=359 volume=46
Oct 11 19:57:31 volumio-gilly volumio5-onboarding[1660]: time=2026-10-11T19:57:31.369+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02,192.168.1.8:48774 @ 0x262f9b0" state=STATUS_PLAYING positionMs=359 volume=46
Oct 11 19:57:31 volumio-gilly volumio5-onboarding[1660]: time=2026-10-11T19:57:31.369+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.8:48774 @ 0x22f0030" id=motherearthradio/radio/flac192 title=Radio
Oct 11 19:57:31 volumio-gilly volumio5-onboarding[1660]: time=2026-10-11T19:57:31.369+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.8:48774 @ 0x22f0030" id=motherearthradio/radio/flac192 title=Radio
Oct 11 19:57:31 volumio-gilly volumio5-onboarding[1660]: time=2026-10-11T19:57:31.369+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%02,192.168.1.8:48774 @ 0x262f9b0" id=motherearthradio/radio/flac192 title=Radio
Oct 11 19:57:31 volumio-gilly volumio5-onboarding[1660]: time=2026-10-11T19:57:31.369+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%02,192.168.1.8:48774 @ 0x262f9b0" id=motherearthradio/radio/flac192 title=Radio
Oct 11 19:57:31 volumio-gilly volumio5-onboarding[1660]: time=2026-10-11T19:57:31.369+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.8:48774 @ 0x22f0030" id=motherearthradio/radio/flac192 title=Radio
Oct 11 19:57:31 volumio-gilly volumio5-onboarding[1660]: time=2026-10-11T19:57:31.369+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%02,192.168.1.8:48774 @ 0x262f9b0" id=motherearthradio/radio/flac192 title=Radio
Oct 11 19:57:31 volumio-gilly volumio[1391]: info: ------------------------------ 66ms
Oct 11 19:57:31 volumio-gilly volumio[1391]: info: ------------------------------ 63ms
Oct 11 19:57:31 volumio-gilly volumio[1391]: info: ------------------------------ 62ms
Oct 11 19:57:31 volumio-gilly volumio[1391]: info: sendMpdCommand playlistinfo took 22 milliseconds
Oct 11 19:57:31 volumio-gilly volumio[1391]: verbose: ControllerMpd::parseTrackInfo
Oct 11 19:57:31 volumio-gilly volumio[1391]: info: ControllerMpd::pushState
Oct 11 19:57:31 volumio-gilly volumio[1391]: info: CoreCommandRouter::servicePushState
Oct 11 19:57:31 volumio-gilly volumio[1391]: info: CoreStateMachine::pushState
Oct 11 19:57:31 volumio-gilly volumio[1391]: info: CorePlayQueue::getTrack 0
Oct 11 19:57:31 volumio-gilly volumio[1391]: info: CoreCommandRouter::volumioPushState
Oct 11 19:57:31 volumio-gilly volumio[1391]: info: CorePlayQueue::getTrack 0
Oct 11 19:57:31 volumio-gilly volumio[1391]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd
Oct 11 19:57:31 volumio-gilly volumio5-onboarding[1660]: time=2026-10-11T19:57:31.384+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.8:48774 @ 0x22f0030" state=STATUS_PLAYING positionMs=359 volume=46
Oct 11 19:57:31 volumio-gilly volumio5-onboarding[1660]: time=2026-10-11T19:57:31.384+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02,192.168.1.8:48774 @ 0x262f9b0" state=STATUS_PLAYING positionMs=359 volume=46
Oct 11 19:57:31 volumio-gilly volumio5-onboarding[1660]: time=2026-10-11T19:57:31.384+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.8:48774 @ 0x22f0030" id=motherearthradio/radio/flac192 title=Radio
Oct 11 19:57:31 volumio-gilly volumio5-onboarding[1660]: time=2026-10-11T19:57:31.384+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%02,192.168.1.8:48774 @ 0x262f9b0" id=motherearthradio/radio/flac192 title=Radio
Oct 11 19:57:31 volumio-gilly volumio[1391]: info: ------------------------------ 62ms
Oct 11 19:57:31 volumio-gilly volumio[1391]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46
Oct 11 19:57:31 volumio-gilly volumio[1391]: SPOTIFY: SPOTIFY VOLUME 47
Oct 11 19:57:31 volumio-gilly volumio[1391]: SPOTIFY: VOLUMIO VOLUME 46
Oct 11 19:57:31 volumio-gilly volumio[1391]: SPOTIFY: DELTA VOLUME ENOUGH: false
Oct 11 19:57:31 volumio-gilly volumio[1391]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46
Oct 11 19:57:31 volumio-gilly volumio[1391]: SPOTIFY: SPOTIFY VOLUME 47
Oct 11 19:57:31 volumio-gilly volumio[1391]: SPOTIFY: VOLUMIO VOLUME 46
Oct 11 19:57:31 volumio-gilly volumio[1391]: SPOTIFY: DELTA VOLUME ENOUGH: false
Oct 11 19:57:31 volumio-gilly volumio[1391]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46
Oct 11 19:57:31 volumio-gilly volumio[1391]: SPOTIFY: SPOTIFY VOLUME 47
Oct 11 19:57:31 volumio-gilly volumio[1391]: SPOTIFY: VOLUMIO VOLUME 46
Oct 11 19:57:31 volumio-gilly volumio[1391]: SPOTIFY: DELTA VOLUME ENOUGH: false
Oct 11 19:57:31 volumio-gilly volumio[1391]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46
Oct 11 19:57:31 volumio-gilly volumio[1391]: SPOTIFY: SPOTIFY VOLUME 47
Oct 11 19:57:31 volumio-gilly volumio[1391]: SPOTIFY: VOLUMIO VOLUME 46
Oct 11 19:57:31 volumio-gilly volumio[1391]: SPOTIFY: DELTA VOLUME ENOUGH: false
Oct 11 19:57:33 volumio-gilly volumio[1391]: info: Preload queue cleared
Oct 11 19:57:33 volumio-gilly volumio[1391]: info: CoreCommandRouter::volumioReplaceandPlayItems
Oct 11 19:57:33 volumio-gilly volumio[1391]: info: CoreStateMachine::ClearQueue
Oct 11 19:57:33 volumio-gilly volumio[1391]: info: CoreStateMachine::stop
Oct 11 19:57:33 volumio-gilly volumio[1391]: info: CoreStateMachine::setConsumeUpdateService undefined
Oct 11 19:57:33 volumio-gilly volumio[1391]: info: CoreStateMachine::stPlaybackTimer
Oct 11 19:57:33 volumio-gilly volumio[1391]: info: CoreStateMachine::updateTrackBlock
Oct 11 19:57:33 volumio-gilly volumio[1391]: info: CorePlayQueue::getTrackBlock
Oct 11 19:57:33 volumio-gilly volumio[1391]: info: CoreStateMachine::pushState
Oct 11 19:57:33 volumio-gilly volumio[1391]: info: CorePlayQueue::getTrack 0
Oct 11 19:57:33 volumio-gilly volumio[1391]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 11 19:57:33 volumio-gilly volumio[1391]: info: CoreCommandRouter::volumioPushState
Oct 11 19:57:33 volumio-gilly volumio[1391]: info: CoreStateMachine::serviceStop
Oct 11 19:57:33 volumio-gilly volumio[1391]: info: CorePlayQueue::getTrack 0
Oct 11 19:57:33 volumio-gilly volumio[1391]: info: CoreCommandRouter::serviceStop
Oct 11 19:57:33 volumio-gilly volumio[1391]: info: CoreCommandRouter::servicePushState
Oct 11 19:57:33 volumio-gilly volumio[1391]: info: CoreStateMachine::pushState
Oct 11 19:57:33 volumio-gilly volumio[1391]: info: CorePlayQueue::getTrack 0
Oct 11 19:57:33 volumio-gilly volumio[1391]: info: CoreCommandRouter::volumioPushState
Oct 11 19:57:33 volumio-gilly volumio[1391]: info: CorePlayQueue::getTrack 0
Oct 11 19:57:33 volumio-gilly volumio[1391]: verbose: STATE SERVICE {"status":"stop","service":"motherearthradio"}
Oct 11 19:57:33 volumio-gilly volumio[1391]: verbose: CURRENT POSITION 0
Oct 11 19:57:33 volumio-gilly volumio[1391]: info: CoreStateMachine::syncState stateService stop
Oct 11 19:57:33 volumio-gilly volumio[1391]: info: CoreStateMachine::syncState currentStatus stop
Oct 11 19:57:33 volumio-gilly volumio[1391]: info: CoreStateMachine::pushState
Oct 11 19:57:33 volumio-gilly volumio[1391]: info: CorePlayQueue::getTrack 0
Oct 11 19:57:33 volumio-gilly volumio[1391]: info: CoreCommandRouter::volumioPushState
Oct 11 19:57:33 volumio-gilly volumio[1391]: info: No code
Oct 11 19:57:33 volumio-gilly volumio[1391]: info: CoreStateMachine::pushState
Oct 11 19:57:33 volumio-gilly volumio[1391]: info: CorePlayQueue::getTrack 0
Oct 11 19:57:33 volumio-gilly volumio[1391]: info: CoreCommandRouter::volumioPushState
Oct 11 19:57:33 volumio-gilly volumio[1391]: info: ControllerMpd::stop
Oct 11 19:57:33 volumio-gilly volumio[1391]: verbose: ControllerMpd::sendMpdCommand stop
Oct 11 19:57:33 volumio-gilly volumio[1391]: info: CorePlayQueue::clearPlayQueue
Oct 11 19:57:33 volumio-gilly volumio[1391]: info: CorePlayQueue::saveQueue
Oct 11 19:57:33 volumio-gilly volumio[1391]: info: CoreCommandRouter::volumioPushQueue
Oct 11 19:57:33 volumio-gilly volumio[1391]: info: CoreStateMachine::addQueueItems
Oct 11 19:57:33 volumio-gilly volumio[1391]: info: CorePlayQueue::addQueueItems
Oct 11 19:57:33 volumio-gilly volumio[1391]: info: Preload queue cleared
Oct 11 19:57:33 volumio-gilly volumio[1391]: info: Adding Item to queue: motherearthradio/radio/flac96
Oct 11 19:57:33 volumio-gilly volumio[1391]: info: Exploding uri motherearthradio/radio/flac96 in service motherearthradio
Oct 11 19:57:33 volumio-gilly volumio5-onboarding[1660]: time=2026-10-11T19:57:33.995+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.8:48774 @ 0x22f0030" state=STATUS_STOPPED positionMs=0 volume=46
Oct 11 19:57:33 volumio-gilly volumio5-onboarding[1660]: time=2026-10-11T19:57:33.995+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02,192.168.1.8:48774 @ 0x262f9b0" state=STATUS_STOPPED positionMs=0 volume=46
Oct 11 19:57:33 volumio-gilly volumio5-onboarding[1660]: time=2026-10-11T19:57:33.995+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.8:48774 @ 0x22f0030" id=motherearthradio/radio/flac192 title=Radio
Oct 11 19:57:33 volumio-gilly volumio5-onboarding[1660]: time=2026-10-11T19:57:33.995+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%02,192.168.1.8:48774 @ 0x262f9b0" id=motherearthradio/radio/flac192 title=Radio
Oct 11 19:57:33 volumio-gilly volumio5-onboarding[1660]: time=2026-10-11T19:57:33.997+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.8:48774 @ 0x22f0030" state=STATUS_STOPPED positionMs=0 volume=46
Oct 11 19:57:33 volumio-gilly volumio5-onboarding[1660]: time=2026-10-11T19:57:33.998+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02,192.168.1.8:48774 @ 0x262f9b0" state=STATUS_STOPPED positionMs=0 volume=46
Oct 11 19:57:33 volumio-gilly volumio5-onboarding[1660]: time=2026-10-11T19:57:33.998+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.8:48774 @ 0x22f0030" id=motherearthradio/radio/flac192 title=Radio
Oct 11 19:57:33 volumio-gilly volumio5-onboarding[1660]: time=2026-10-11T19:57:33.998+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%02,192.168.1.8:48774 @ 0x262f9b0" id=motherearthradio/radio/flac192 title=Radio
Oct 11 19:57:33 volumio-gilly volumio5-onboarding[1660]: time=2026-10-11T19:57:33.998+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.8:48774 @ 0x22f0030" state=STATUS_STOPPED positionMs=0 volume=46
Oct 11 19:57:33 volumio-gilly volumio5-onboarding[1660]: time=2026-10-11T19:57:33.998+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02,192.168.1.8:48774 @ 0x262f9b0" state=STATUS_STOPPED positionMs=0 volume=46
Oct 11 19:57:33 volumio-gilly volumio5-onboarding[1660]: time=2026-10-11T19:57:33.999+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.8:48774 @ 0x22f0030" state=STATUS_STOPPED positionMs=0 volume=46
Oct 11 19:57:34 volumio-gilly volumio5-onboarding[1660]: time=2026-10-11T19:57:34.000+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02,192.168.1.8:48774 @ 0x262f9b0" state=STATUS_STOPPED positionMs=0 volume=46
Oct 11 19:57:34 volumio-gilly volumio5-onboarding[1660]: time=2026-10-11T19:57:34.000+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.8:48774 @ 0x22f0030" id=motherearthradio/radio/flac192 title=Radio
Oct 11 19:57:34 volumio-gilly volumio5-onboarding[1660]: time=2026-10-11T19:57:34.001+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%02,192.168.1.8:48774 @ 0x262f9b0" id=motherearthradio/radio/flac192 title=Radio
Oct 11 19:57:34 volumio-gilly volumio5-onboarding[1660]: time=2026-10-11T19:57:34.002+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.8:48774 @ 0x22f0030" id=motherearthradio/radio/flac192 title=Radio
Oct 11 19:57:34 volumio-gilly volumio5-onboarding[1660]: time=2026-10-11T19:57:34.002+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%02,192.168.1.8:48774 @ 0x262f9b0" id=motherearthradio/radio/flac192 title=Radio
Oct 11 19:57:34 volumio-gilly volumio[1391]: info: CoreCommandRouter::volumioPushQueue
Oct 11 19:57:34 volumio-gilly volumio[1391]: info: CorePlayQueue::saveQueue
Oct 11 19:57:34 volumio-gilly volumio[1391]: info: CoreStateMachine::updateTrackBlock
Oct 11 19:57:34 volumio-gilly volumio[1391]: info: CorePlayQueue::getTrackBlock
Oct 11 19:57:34 volumio-gilly volumio[1391]: info: CoreCommandRouter::volumioPlay
Oct 11 19:57:34 volumio-gilly volumio[1391]: info: CoreStateMachine::play index 0
Oct 11 19:57:34 volumio-gilly volumio[1391]: info: CoreStateMachine::setConsumeUpdateService undefined
Oct 11 19:57:34 volumio-gilly volumio[1391]: info: CoreStateMachine::stop
Oct 11 19:57:34 volumio-gilly volumio[1391]: info: CoreStateMachine::setConsumeUpdateService undefined
Oct 11 19:57:34 volumio-gilly volumio[1391]: info: CoreStateMachine::play index undefined
Oct 11 19:57:34 volumio-gilly volumio[1391]: info: CoreStateMachine::setConsumeUpdateService undefined
Oct 11 19:57:34 volumio-gilly volumio[1391]: info: CorePlayQueue::getTrack 0
Oct 11 19:57:34 volumio-gilly volumio[1391]: info: CoreStateMachine::startPlaybackTimer
Oct 11 19:57:34 volumio-gilly volumio[1391]: info: CorePlayQueue::getTrack 0
Oct 11 19:57:34 volumio-gilly volumio[1391]: info: [MER] ▶️ Playing: https://stream.motherearthradio.de/listen/motherearth/motherearth.flac-lo
Oct 11 19:57:34 volumio-gilly volumio[1391]: info: [MER] 🔌 Starting SSE for Radio
Oct 11 19:57:34 volumio-gilly volumio[1391]: verbose: ControllerMpd::sendMpdCommand stop
Oct 11 19:57:34 volumio-gilly volumio[1391]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Oct 11 19:57:34 volumio-gilly volumio[1391]: Error: socket hang up
Oct 11 19:57:34 volumio-gilly volumio[1391]: at connResetException (node:internal/errors:720:14)
Oct 11 19:57:34 volumio-gilly volumio[1391]: at TLSSocket.socketCloseListener (node:_http_client:468:25)
Oct 11 19:57:34 volumio-gilly volumio[1391]: at TLSSocket.emit (node:events:526:35)
Oct 11 19:57:34 volumio-gilly volumio[1391]: at node:net:337:12
Oct 11 19:57:34 volumio-gilly volumio[1391]: at TCP.done (node:_tls_wrap:631:7) {
Oct 11 19:57:34 volumio-gilly volumio[1391]: code: 'ECONNRESET'
Oct 11 19:57:34 volumio-gilly volumio[1391]: }
Oct 11 19:57:34 volumio-gilly volumio[1391]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Oct 11 19:57:36 volumio-gilly sudo[16004]: volumio : unable to resolve host volumio-gilly: System error
Oct 11 19:57:36 volumio-gilly sudo[16004]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-11 19:56'
Oct 11 19:57:36 volumio-gilly sudo[16004]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)"
NAME="Raspbian GNU/Linux"
VERSION_ID="12"
VERSION="12 (bookworm)"
VERSION_CODENAME=bookworm
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="18952480e8d8c63f22208e9007a0f47a9563eae6"
VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd"
VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2"
VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026"
VOLUMIO_VERSION="4.119"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"