-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Sat 2025-07-19 16:07:51 CEST. -- Jul 19 16:06:00 volumio volumio[893]: info: BOOT COMPLETED Jul 19 16:06:00 volumio volumio[893]: info: Preload queue cleared Jul 19 16:06:03 volumio ntpd[781]: Soliciting pool server 89.46.74.148 Jul 19 16:06:04 volumio ntpd[781]: Soliciting pool server 93.94.88.50 Jul 19 16:06:05 volumio ntpd[781]: Soliciting pool server 95.110.254.234 Jul 19 16:06:06 volumio ntpd[781]: Soliciting pool server 81.56.230.156 Jul 19 16:06:06 volumio ntpd[781]: Soliciting pool server 212.45.144.206 Jul 19 16:06:06 volumio ntpd[781]: Soliciting pool server 212.45.144.3 Jul 19 16:06:07 volumio ntpd[781]: Soliciting pool server 162.159.200.123 Jul 19 16:06:07 volumio ntpd[781]: Soliciting pool server 162.159.200.123 Jul 19 16:06:07 volumio ntpd[781]: Soliciting pool server 129.152.16.145 Jul 19 16:06:08 volumio ntpd[781]: Soliciting pool server 85.199.214.99 Jul 19 16:06:08 volumio ntpd[781]: Soliciting pool server 151.22.209.90 Jul 19 16:06:09 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 19 16:06:09 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 19 16:06:09 volumio volumio[893]: info: Discovery: Getting this device information Jul 19 16:06:09 volumio volumio[893]: info: CoreCommandRouter::volumioGetState Jul 19 16:06:09 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 19 16:06:09 volumio ntpd[781]: Soliciting pool server 162.159.200.1 Jul 19 16:06:10 volumio ntpd[781]: Soliciting pool server 2a00:6d41:10:1194::3 Jul 19 16:06:13 volumio ntpd[781]: receive: Unexpected origin timestamp 0xec2624d4.bf8a2127 does not match aorg 0000000000.00000000 from server@151.22.209.90 xmt 0xec2624d5.a7d81b6a Jul 19 16:06:13 volumio ntpd[781]: receive: Unexpected origin timestamp 0xec2624d4.bf8d6a1f does not match aorg 0000000000.00000000 from server@93.94.88.50 xmt 0xec2624d5.a9b3248e Jul 19 16:06:14 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Jul 19 16:06:14 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand update Jul 19 16:06:25 volumio volumio[893]: info: CoreCommandRouter::volumioGetState Jul 19 16:06:27 volumio volumio[893]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 19 16:06:27 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jul 19 16:06:27 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 16:06:27 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 19 16:06:27 volumio volumio[893]: info: Getting Alsa Cards List without I2S DAC Jul 19 16:06:27 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Jul 19 16:06:27 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 16:06:27 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 19 16:06:27 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 19 16:06:27 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 19 16:06:27 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 19 16:06:27 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 19 16:06:27 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 19 16:06:27 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 19 16:06:27 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jul 19 16:06:27 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 19 16:06:38 volumio volumio[893]: info: CALLMETHOD: music_service mpd savePlaybackOptions [object Object] Jul 19 16:06:38 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , savePlaybackOptions Jul 19 16:06:38 volumio sudo[1636]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 19 16:06:38 volumio sudo[1636]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 16:06:38 volumio sudo[1636]: pam_unix(sudo:session): session closed for user root Jul 19 16:06:38 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 16:06:38 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 16:06:38 volumio sudo[1638]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 19 16:06:38 volumio sudo[1638]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 16:06:38 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 16:06:38 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 16:06:38 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 16:06:38 volumio systemd[1]: Stopping Music Player Daemon... Jul 19 16:06:38 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 16:06:38 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 16:06:38 volumio volumio[893]: info: MPD Permissions set Jul 19 16:06:38 volumio systemd[1]: mpd.service: Succeeded. Jul 19 16:06:38 volumio systemd[1]: Stopped Music Player Daemon. Jul 19 16:06:38 volumio systemd[1]: Starting Music Player Daemon... Jul 19 16:06:38 volumio sudo[1647]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 19 16:06:38 volumio sudo[1647]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 16:06:38 volumio sudo[1647]: pam_unix(sudo:session): session closed for user root Jul 19 16:06:39 volumio mpd[1649]: Jul 19 16:06 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 19 16:06:39 volumio systemd[1]: Started Music Player Daemon. Jul 19 16:06:39 volumio sudo[1638]: pam_unix(sudo:session): session closed for user root Jul 19 16:06:39 volumio volumio[893]: error: updateQueue error: null Jul 19 16:06:39 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 19 16:06:39 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 19 16:06:39 volumio volumio[893]: info: Discovery: Getting this device information Jul 19 16:06:39 volumio volumio[893]: info: CoreCommandRouter::volumioGetState Jul 19 16:06:39 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 19 16:06:49 volumio volumio[893]: info: CoreCommandRouter::volumioStop Jul 19 16:06:49 volumio volumio[893]: info: CoreStateMachine::stop Jul 19 16:06:49 volumio volumio[893]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 19 16:06:49 volumio volumio[893]: info: CoreStateMachine::stPlaybackTimer Jul 19 16:06:49 volumio volumio[893]: info: CoreStateMachine::updateTrackBlock Jul 19 16:06:49 volumio volumio[893]: info: CorePlayQueue::getTrackBlock Jul 19 16:06:49 volumio volumio[893]: info: CoreStateMachine::pushState Jul 19 16:06:49 volumio volumio[893]: info: CorePlayQueue::getTrack 0 Jul 19 16:06:49 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 19 16:06:49 volumio volumio[893]: info: CoreCommandRouter::volumioPushState Jul 19 16:06:49 volumio volumio[893]: info: CoreStateMachine::serviceStop Jul 19 16:06:49 volumio volumio[893]: info: CorePlayQueue::getTrack 0 Jul 19 16:06:49 volumio volumio[893]: info: CoreCommandRouter::serviceStop Jul 19 16:06:49 volumio volumio[893]: info: [1752934009146] ControllerWebradio::stop Jul 19 16:06:49 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand stop Jul 19 16:06:49 volumio volumio[893]: info: sendMpdCommand stop took 14 milliseconds Jul 19 16:06:50 volumio volumio[893]: info: CoreCommandRouter::volumioPlay Jul 19 16:06:50 volumio volumio[893]: info: CoreStateMachine::play index undefined Jul 19 16:06:50 volumio volumio[893]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 19 16:06:50 volumio volumio[893]: info: CorePlayQueue::getTrack 0 Jul 19 16:06:50 volumio volumio[893]: info: CoreStateMachine::startPlaybackTimer Jul 19 16:06:50 volumio volumio[893]: info: CorePlayQueue::getTrack 0 Jul 19 16:06:50 volumio volumio[893]: info: [1752934010612] ControllerWebradio::clearAddPlayTrack Jul 19 16:06:50 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand stop Jul 19 16:06:50 volumio volumio[893]: info: sendMpdCommand stop took 2 milliseconds Jul 19 16:06:50 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand clear Jul 19 16:06:50 volumio volumio[893]: info: Jul 19 16:06:50 volumio volumio[893]: ---------------------------- MPD announces system playlist update Jul 19 16:06:50 volumio volumio[893]: info: Ignoring MPD Status Update Jul 19 16:06:50 volumio volumio[893]: info: sendMpdCommand clear took 2 milliseconds Jul 19 16:06:50 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand load "http://edge.radiomontecarlo.net/RMC.mp3" Jul 19 16:06:50 volumio volumio[893]: error: updateQueue error: null Jul 19 16:06:50 volumio volumio[893]: info: ------------------------------ 4ms Jul 19 16:06:52 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand add "http://edge.radiomontecarlo.net/RMC.mp3" Jul 19 16:06:52 volumio volumio[893]: info: Jul 19 16:06:52 volumio volumio[893]: ---------------------------- MPD announces system playlist update Jul 19 16:06:52 volumio volumio[893]: info: Ignoring MPD Status Update Jul 19 16:06:52 volumio volumio[893]: info: sendMpdCommand add "http://edge.radiomontecarlo.net/RMC.mp3" took 3 milliseconds Jul 19 16:06:52 volumio volumio[893]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 19 16:06:52 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand play Jul 19 16:06:52 volumio volumio[893]: info: ------------------------------ 5ms Jul 19 16:06:52 volumio volumio[893]: info: sendMpdCommand play took 4 milliseconds Jul 19 16:06:53 volumio volumio[893]: info: Jul 19 16:06:53 volumio volumio[893]: ---------------------------- MPD announces state update: player Jul 19 16:06:53 volumio volumio[893]: info: ControllerMpd::getState Jul 19 16:06:53 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand status Jul 19 16:06:53 volumio volumio[893]: info: FusionDsp - ---- read samplerate, raw: 48000,S32_LE,2,32 Jul 19 16:06:53 volumio volumio[893]: info: FusionDsp - ---- read samplerate from file: 48000 Jul 19 16:06:53 volumio volumio[893]: info: camilladsp stopping service pid 1650... Jul 19 16:06:53 volumio volumio[893]: info: camilladsp service terminated, instance 1 Jul 19 16:06:53 volumio volumio[893]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 19 16:06:53 volumio volumio[893]: info: camilladsp service started and running in background, instance 1 Jul 19 16:06:53 volumio volumio[893]: info: Jul 19 16:06:53 volumio volumio[893]: ---------------------------- MPD announces state update: player Jul 19 16:06:53 volumio volumio[893]: info: sendMpdCommand status took 53 milliseconds Jul 19 16:06:53 volumio volumio[893]: info: ControllerMpd::getState Jul 19 16:06:53 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand status Jul 19 16:06:53 volumio volumio[893]: verbose: ControllerMpd::parseState Jul 19 16:06:53 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 19 16:06:53 volumio volumio[893]: info: Jul 19 16:06:53 volumio volumio[893]: ---------------------------- MPD announces system playlist update Jul 19 16:06:53 volumio volumio[893]: info: Ignoring MPD Status Update Jul 19 16:06:53 volumio volumio[893]: info: Jul 19 16:06:53 volumio volumio[893]: ---------------------------- MPD announces state update: player Jul 19 16:06:53 volumio volumio[893]: info: ControllerMpd::getState Jul 19 16:06:53 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand status Jul 19 16:06:53 volumio volumio[893]: info: sendMpdCommand status took 30 milliseconds Jul 19 16:06:53 volumio volumio[893]: info: sendMpdCommand playlistinfo took 29 milliseconds Jul 19 16:06:53 volumio volumio[893]: verbose: ControllerMpd::parseState Jul 19 16:06:53 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 19 16:06:53 volumio volumio[893]: verbose: ControllerMpd::parseTrackInfo Jul 19 16:06:53 volumio volumio[893]: info: ControllerMpd::pushState Jul 19 16:06:53 volumio volumio[893]: info: CoreCommandRouter::servicePushState Jul 19 16:06:53 volumio volumio[893]: info: CorePlayQueue::getTrack 0 Jul 19 16:06:53 volumio volumio[893]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"DAVID BOWIE & PAT METHENY~THIS IS NOT AMERICA~THE FALCON AND THE SNOWMAN OST~1999~~199~2025-07-19T16:02:55~2025-07-19T16:02:55~Radio Monte Carlo","artist":"FM_RMC_mp3_radiomediaset","album":null,"uri":"http://edge.radiomontecarlo.net/RMC.mp3","trackType":"mp3"} Jul 19 16:06:53 volumio volumio[893]: verbose: CURRENT POSITION 0 Jul 19 16:06:53 volumio volumio[893]: info: CoreStateMachine::syncState stateService play Jul 19 16:06:53 volumio volumio[893]: info: CoreStateMachine::syncState currentStatus stop Jul 19 16:06:53 volumio volumio[893]: info: ------------------------------ 97ms Jul 19 16:06:53 volumio volumio[893]: info: ------------------------------ 22ms Jul 19 16:06:53 volumio volumio[893]: info: sendMpdCommand status took 20 milliseconds Jul 19 16:06:53 volumio volumio[893]: info: sendMpdCommand playlistinfo took 12 milliseconds Jul 19 16:06:53 volumio volumio[893]: verbose: ControllerMpd::parseState Jul 19 16:06:53 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 19 16:06:53 volumio volumio[893]: verbose: ControllerMpd::parseTrackInfo Jul 19 16:06:53 volumio volumio[893]: info: ControllerMpd::pushState Jul 19 16:06:53 volumio volumio[893]: info: CoreCommandRouter::servicePushState Jul 19 16:06:53 volumio volumio[893]: info: CorePlayQueue::getTrack 0 Jul 19 16:06:53 volumio volumio[893]: verbose: STATE SERVICE {"status":"play","position":0,"seek":860,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"DAVID BOWIE & PAT METHENY~THIS IS NOT AMERICA~THE FALCON AND THE SNOWMAN OST~1999~~199~2025-07-19T16:02:55~2025-07-19T16:02:55~Radio Monte Carlo","artist":"FM_RMC_mp3_radiomediaset","album":null,"uri":"http://edge.radiomontecarlo.net/RMC.mp3","trackType":"mp3"} Jul 19 16:06:53 volumio volumio[893]: verbose: CURRENT POSITION 0 Jul 19 16:06:53 volumio volumio[893]: info: CoreStateMachine::syncState stateService play Jul 19 16:06:53 volumio volumio[893]: info: CoreStateMachine::syncState currentStatus play Jul 19 16:06:53 volumio volumio[893]: info: Received an update from plugin. extracting info from payload Jul 19 16:06:53 volumio volumio[893]: info: CoreStateMachine::pushState Jul 19 16:06:53 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 19 16:06:53 volumio volumio[893]: info: CoreCommandRouter::volumioPushState Jul 19 16:06:53 volumio volumio[893]: info: CoreStateMachine::pushState Jul 19 16:06:53 volumio volumio[893]: info: CoreCommandRouter::volumioPushState Jul 19 16:06:53 volumio volumio[893]: info: ------------------------------ 99ms Jul 19 16:06:53 volumio volumio[893]: info: sendMpdCommand playlistinfo took 50 milliseconds Jul 19 16:06:53 volumio volumio[893]: verbose: ControllerMpd::parseTrackInfo Jul 19 16:06:53 volumio volumio[893]: info: ControllerMpd::pushState Jul 19 16:06:53 volumio volumio[893]: info: CoreCommandRouter::servicePushState Jul 19 16:06:53 volumio volumio[893]: info: CorePlayQueue::getTrack 0 Jul 19 16:06:53 volumio volumio[893]: verbose: STATE SERVICE {"status":"play","position":0,"seek":860,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"DAVID BOWIE & PAT METHENY~THIS IS NOT AMERICA~THE FALCON AND THE SNOWMAN OST~1999~~199~2025-07-19T16:02:55~2025-07-19T16:02:55~Radio Monte Carlo","artist":"FM_RMC_mp3_radiomediaset","album":null,"uri":"http://edge.radiomontecarlo.net/RMC.mp3","trackType":"mp3"} Jul 19 16:06:53 volumio volumio[893]: verbose: CURRENT POSITION 0 Jul 19 16:06:53 volumio volumio[893]: info: CoreStateMachine::syncState stateService play Jul 19 16:06:53 volumio volumio[893]: info: CoreStateMachine::syncState currentStatus play Jul 19 16:06:53 volumio volumio[893]: info: Received an update from plugin. extracting info from payload Jul 19 16:06:53 volumio volumio[893]: info: CoreStateMachine::pushState Jul 19 16:06:53 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 19 16:06:53 volumio volumio[893]: info: CoreCommandRouter::volumioPushState Jul 19 16:06:53 volumio volumio[893]: info: CoreStateMachine::pushState Jul 19 16:06:53 volumio volumio[893]: info: CoreCommandRouter::volumioPushState Jul 19 16:06:53 volumio volumio[893]: info: ------------------------------ 119ms Jul 19 16:07:18 volumio volumio[893]: info: CoreCommandRouter::volumioGetState Jul 19 16:07:21 volumio volumio[893]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 19 16:07:21 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jul 19 16:07:21 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 16:07:21 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 19 16:07:21 volumio volumio[893]: info: Getting Alsa Cards List without I2S DAC Jul 19 16:07:21 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Jul 19 16:07:21 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 16:07:21 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 19 16:07:21 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 19 16:07:21 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 19 16:07:21 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 19 16:07:21 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 19 16:07:21 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 19 16:07:21 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 19 16:07:21 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jul 19 16:07:21 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 19 16:07:21 volumio volumio[893]: info: Jul 19 16:07:21 volumio volumio[893]: ---------------------------- MPD announces system playlist update Jul 19 16:07:21 volumio volumio[893]: info: Ignoring MPD Status Update Jul 19 16:07:21 volumio volumio[893]: info: Jul 19 16:07:21 volumio volumio[893]: ---------------------------- MPD announces state update: player Jul 19 16:07:21 volumio volumio[893]: info: ControllerMpd::getState Jul 19 16:07:21 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand status Jul 19 16:07:21 volumio volumio[893]: info: ------------------------------ 4ms Jul 19 16:07:21 volumio volumio[893]: info: sendMpdCommand status took 2 milliseconds Jul 19 16:07:21 volumio volumio[893]: verbose: ControllerMpd::parseState Jul 19 16:07:21 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 19 16:07:21 volumio volumio[893]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 19 16:07:21 volumio volumio[893]: verbose: ControllerMpd::parseTrackInfo Jul 19 16:07:21 volumio volumio[893]: info: ControllerMpd::pushState Jul 19 16:07:21 volumio volumio[893]: info: CoreCommandRouter::servicePushState Jul 19 16:07:21 volumio volumio[893]: info: CorePlayQueue::getTrack 0 Jul 19 16:07:21 volumio volumio[893]: verbose: STATE SERVICE {"status":"play","position":0,"seek":30355,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Radio Monte Carlo","artist":"FM_RMC_mp3_radiomediaset","album":null,"uri":"http://edge.radiomontecarlo.net/RMC.mp3","trackType":"mp3"} Jul 19 16:07:21 volumio volumio[893]: verbose: CURRENT POSITION 0 Jul 19 16:07:21 volumio volumio[893]: info: CoreStateMachine::syncState stateService play Jul 19 16:07:21 volumio volumio[893]: info: CoreStateMachine::syncState currentStatus play Jul 19 16:07:21 volumio volumio[893]: info: Received an update from plugin. extracting info from payload Jul 19 16:07:21 volumio volumio[893]: info: CoreStateMachine::pushState Jul 19 16:07:21 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 19 16:07:21 volumio volumio[893]: info: CoreCommandRouter::volumioPushState Jul 19 16:07:21 volumio volumio[893]: info: CoreStateMachine::pushState Jul 19 16:07:21 volumio volumio[893]: info: CoreCommandRouter::volumioPushState Jul 19 16:07:21 volumio volumio[893]: info: ------------------------------ 49ms Jul 19 16:07:27 volumio volumio[893]: info: CoreCommandRouter::volumioGetState Jul 19 16:07:27 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 19 16:07:27 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 19 16:07:27 volumio volumio[893]: info: Discovery: Getting this device information Jul 19 16:07:27 volumio volumio[893]: info: CoreCommandRouter::volumioGetState Jul 19 16:07:27 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 19 16:07:31 volumio volumio[893]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 19 16:07:31 volumio volumio[893]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 19 16:07:31 volumio volumio[893]: info: Retrieving Cloud Streaming UI Jul 19 16:07:31 volumio volumio[893]: info: Getting Tidal Cloud Configuration Jul 19 16:07:31 volumio volumio[893]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 19 16:07:31 volumio volumio[893]: info: Getting Qobuz Cloud Configuration Jul 19 16:07:31 volumio volumio[893]: info: Asking plugin for UI Config Jul 19 16:07:31 volumio volumio[893]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 19 16:07:31 volumio volumio[893]: info: Getting Spotify Cloud Configuration Jul 19 16:07:31 volumio volumio[893]: info: Asking plugin for UI Config Jul 19 16:07:31 volumio volumio[893]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 19 16:07:31 volumio volumio[893]: info: Saving Spotify Acccount Jul 19 16:07:31 volumio volumio[893]: info: Got Tidal Cloud Configuration Jul 19 16:07:31 volumio volumio[893]: info: Got it Jul 19 16:07:31 volumio volumio[893]: info: Got it Jul 19 16:07:31 volumio volumio[893]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jul 19 16:07:31 volumio volumio[893]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 19 16:07:31 volumio volumio[893]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Jul 19 16:07:31 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 19 16:07:31 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 19 16:07:31 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 19 16:07:31 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 19 16:07:31 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 19 16:07:31 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 19 16:07:31 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 19 16:07:31 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 19 16:07:31 volumio volumio[893]: info: CoreCommandRouter::volumioGetBrowseSources Jul 19 16:07:31 volumio volumio[893]: info: CoreCommandRouter::volumioGetBrowseSources Jul 19 16:07:31 volumio volumio[893]: info: CoreCommandRouter::volumioGetBrowseSources Jul 19 16:07:32 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 19 16:07:32 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jul 19 16:07:33 volumio volumio[893]: verbose: New Socket.io Connection to 192.168.8.158:3000 from 192.168.8.109 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Jul 19 16:07:36 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 19 16:07:40 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 19 16:07:44 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 19 16:07:45 volumio volumio[893]: verbose: New Socket.io Connection to 192.168.8.158:3000 from 192.168.8.109 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Jul 19 16:07:48 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 19 16:07:50 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Jul 19 16:07:50 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Jul 19 16:07:50 volumio volumio[893]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 19 16:07:50 volumio volumio[893]: Error: getaddrinfo -3007 Jul 19 16:07:50 volumio volumio[893]: at errnoException (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:199:11) Jul 19 16:07:50 volumio volumio[893]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:112:10) Jul 19 16:07:50 volumio volumio[893]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) { Jul 19 16:07:50 volumio volumio[893]: code: -3007, Jul 19 16:07:50 volumio volumio[893]: errno: -3007, Jul 19 16:07:50 volumio volumio[893]: syscall: 'getaddrinfo' Jul 19 16:07:50 volumio volumio[893]: } Jul 19 16:07:50 volumio volumio[893]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 19 16:07:51 volumio sudo[1996]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-19 16:06 Jul 19 16:07:51 volumio sudo[1996]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="811ff8312d42f9da0260be1d353081a805678820" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 02 Jul 2025 04:07:25 PM CEST" VOLUMIO_VERSION="3.819" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3b9115b1943141a9d7186aa434f8f3c2"