-- Logs begin at Fri 2025-10-10 23:43:17 BST, end at Wed 2025-10-29 22:00:46 GMT. -- Oct 29 21:59:06 volumio go-librespot[1456]: time="2025-10-29T21:59:06Z" level=trace msg="sent dealer ping" Oct 29 21:59:06 volumio go-librespot[1456]: time="2025-10-29T21:59:06Z" level=trace msg="received dealer pong" Oct 29 21:59:11 volumio kernel: hwmon hwmon1: Voltage normalised Oct 29 21:59:15 volumio kernel: hwmon hwmon1: Undervoltage detected! Oct 29 21:59:26 volumio kernel: hwmon hwmon1: Voltage normalised Oct 29 21:59:28 volumio kernel: hwmon hwmon1: Undervoltage detected! Oct 29 21:59:32 volumio kernel: hwmon hwmon1: Voltage normalised Oct 29 21:59:36 volumio kernel: hwmon hwmon1: Undervoltage detected! Oct 29 21:59:36 volumio go-librespot[1456]: time="2025-10-29T21:59:36Z" level=trace msg="sent dealer ping" Oct 29 21:59:36 volumio go-librespot[1456]: time="2025-10-29T21:59:36Z" level=trace msg="received dealer pong" Oct 29 21:59:53 volumio kernel: hwmon hwmon1: Voltage normalised Oct 29 21:59:57 volumio kernel: hwmon hwmon1: Undervoltage detected! Oct 29 22:00:03 volumio kernel: hwmon hwmon1: Voltage normalised Oct 29 22:00:06 volumio go-librespot[1456]: time="2025-10-29T22:00:06Z" level=trace msg="sent dealer ping" Oct 29 22:00:06 volumio go-librespot[1456]: time="2025-10-29T22:00:06Z" level=trace msg="received dealer pong" Oct 29 22:00:07 volumio kernel: hwmon hwmon1: Undervoltage detected! Oct 29 22:00:11 volumio kernel: hwmon hwmon1: Voltage normalised Oct 29 22:00:14 volumio kernel: hwmon hwmon1: Undervoltage detected! Oct 29 22:00:20 volumio volumio[1190]: info: Oct 29 22:00:20 volumio volumio[1190]: ---------------------------- MPD announces system playlist update Oct 29 22:00:20 volumio volumio[1190]: info: Ignoring MPD Status Update Oct 29 22:00:20 volumio volumio[1190]: info: Oct 29 22:00:20 volumio volumio[1190]: ---------------------------- MPD announces state update: player Oct 29 22:00:20 volumio volumio[1190]: info: ControllerMpd::getState Oct 29 22:00:20 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand status Oct 29 22:00:20 volumio volumio[1190]: info: Oct 29 22:00:20 volumio volumio[1190]: ---------------------------- MPD announces system playlist update Oct 29 22:00:20 volumio volumio[1190]: info: Ignoring MPD Status Update Oct 29 22:00:20 volumio volumio[1190]: info: Oct 29 22:00:20 volumio volumio[1190]: ---------------------------- MPD announces state update: player Oct 29 22:00:20 volumio volumio[1190]: info: ControllerMpd::getState Oct 29 22:00:20 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand status Oct 29 22:00:20 volumio volumio[1190]: info: Oct 29 22:00:20 volumio volumio[1190]: ---------------------------- MPD announces system playlist update Oct 29 22:00:20 volumio volumio[1190]: info: Ignoring MPD Status Update Oct 29 22:00:20 volumio volumio[1190]: info: Oct 29 22:00:20 volumio volumio[1190]: ---------------------------- MPD announces state update: player Oct 29 22:00:20 volumio volumio[1190]: info: ControllerMpd::getState Oct 29 22:00:20 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand status Oct 29 22:00:20 volumio volumio[1190]: info: ------------------------------ 13ms Oct 29 22:00:20 volumio volumio[1190]: info: sendMpdCommand status took 11 milliseconds Oct 29 22:00:20 volumio volumio[1190]: info: ------------------------------ 11ms Oct 29 22:00:20 volumio volumio[1190]: info: sendMpdCommand status took 9 milliseconds Oct 29 22:00:20 volumio volumio[1190]: info: ------------------------------ 8ms Oct 29 22:00:20 volumio volumio[1190]: info: sendMpdCommand status took 6 milliseconds Oct 29 22:00:20 volumio volumio[1190]: verbose: ControllerMpd::parseState Oct 29 22:00:20 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 22:00:20 volumio volumio[1190]: verbose: ControllerMpd::parseState Oct 29 22:00:20 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 22:00:20 volumio volumio[1190]: verbose: ControllerMpd::parseState Oct 29 22:00:20 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 22:00:20 volumio volumio[1190]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 29 22:00:20 volumio volumio[1190]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 29 22:00:20 volumio volumio[1190]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 29 22:00:20 volumio volumio[1190]: verbose: ControllerMpd::parseTrackInfo Oct 29 22:00:20 volumio volumio[1190]: verbose: ControllerMpd::parseTrackInfo Oct 29 22:00:20 volumio volumio[1190]: verbose: ControllerMpd::parseTrackInfo Oct 29 22:00:20 volumio volumio[1190]: info: ControllerMpd::pushState Oct 29 22:00:20 volumio volumio[1190]: info: CoreCommandRouter::servicePushState Oct 29 22:00:20 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Oct 29 22:00:20 volumio volumio[1190]: verbose: STATE SERVICE {"status":"play","position":0,"seek":14832229,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"89 Kbps","isStreaming":false,"title":"Radio Swiss Jazz - www.radioswissjazz.ch","artist":"Swiss Jazz","album":null,"uri":"http://stream.srg-ssr.ch/m/rsj/aacp_96","trackType":""} Oct 29 22:00:20 volumio volumio[1190]: verbose: CURRENT POSITION 0 Oct 29 22:00:20 volumio volumio[1190]: info: CoreStateMachine::syncState stateService play Oct 29 22:00:20 volumio volumio[1190]: info: CoreStateMachine::syncState currentStatus play Oct 29 22:00:20 volumio volumio[1190]: info: Received an update from plugin. extracting info from payload Oct 29 22:00:20 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 29 22:00:20 volumio volumio[1190]: info: CoreStateMachine::pushState Oct 29 22:00:20 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 29 22:00:20 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Oct 29 22:00:20 volumio volumio[1190]: info: CoreStateMachine::pushState Oct 29 22:00:20 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Oct 29 22:00:20 volumio volumio[1190]: info: ControllerMpd::pushState Oct 29 22:00:20 volumio volumio[1190]: info: CoreCommandRouter::servicePushState Oct 29 22:00:20 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Oct 29 22:00:20 volumio volumio[1190]: verbose: STATE SERVICE {"status":"play","position":0,"seek":14832229,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"89 Kbps","isStreaming":false,"title":"Radio Swiss Jazz - www.radioswissjazz.ch","artist":"Swiss Jazz","album":null,"uri":"http://stream.srg-ssr.ch/m/rsj/aacp_96","trackType":""} Oct 29 22:00:20 volumio volumio[1190]: verbose: CURRENT POSITION 0 Oct 29 22:00:20 volumio volumio[1190]: info: CoreStateMachine::syncState stateService play Oct 29 22:00:20 volumio volumio[1190]: info: CoreStateMachine::syncState currentStatus play Oct 29 22:00:20 volumio volumio[1190]: info: Received an update from plugin. extracting info from payload Oct 29 22:00:20 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 29 22:00:20 volumio volumio[1190]: info: CoreStateMachine::pushState Oct 29 22:00:20 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Oct 29 22:00:20 volumio volumio[1190]: info: CoreStateMachine::pushState Oct 29 22:00:20 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Oct 29 22:00:20 volumio volumio[1190]: info: ControllerMpd::pushState Oct 29 22:00:20 volumio volumio[1190]: info: CoreCommandRouter::servicePushState Oct 29 22:00:20 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Oct 29 22:00:20 volumio volumio[1190]: verbose: STATE SERVICE {"status":"play","position":0,"seek":14832229,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"89 Kbps","isStreaming":false,"title":"Radio Swiss Jazz - www.radioswissjazz.ch","artist":"Swiss Jazz","album":null,"uri":"http://stream.srg-ssr.ch/m/rsj/aacp_96","trackType":""} Oct 29 22:00:20 volumio volumio[1190]: verbose: CURRENT POSITION 0 Oct 29 22:00:20 volumio volumio[1190]: info: CoreStateMachine::syncState stateService play Oct 29 22:00:20 volumio volumio[1190]: info: CoreStateMachine::syncState currentStatus play Oct 29 22:00:20 volumio volumio[1190]: info: Received an update from plugin. extracting info from payload Oct 29 22:00:20 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 29 22:00:20 volumio volumio[1190]: info: CoreStateMachine::pushState Oct 29 22:00:20 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Oct 29 22:00:20 volumio volumio[1190]: info: CoreStateMachine::pushState Oct 29 22:00:20 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Oct 29 22:00:20 volumio volumio[1190]: info: ------------------------------ 60ms Oct 29 22:00:20 volumio volumio[1190]: info: ------------------------------ 58ms Oct 29 22:00:20 volumio volumio[1190]: info: ------------------------------ 56ms Oct 29 22:00:20 volumio volumio[1190]: info: [LastFM] Split composite title into artist: Radio Swiss Jazz and title: www.radioswissjazz.ch Oct 29 22:00:20 volumio volumio[1190]: info: [LastFM] track info: {"success":true,"trackInfo":{"name":"www.radioswissjazz.ch","url":"https://www.last.fm/music/Radio+Swiss+Jazz/_/www.radioswissjazz.ch","duration":"0","streamable":{"#":"0","@":{"fulltrack":"0"}},"listeners":"462","playcount":"26934","artist":{"name":"Radio Swiss Jazz","url":"https://www.last.fm/music/Radio+Swiss+Jazz"},"userplaycount":"14847","userloved":"0","toptags":""}} Oct 29 22:00:20 volumio volumio[1190]: info: [LastFM] Updated missing track duration: 0 Oct 29 22:00:36 volumio go-librespot[1456]: time="2025-10-29T22:00:36Z" level=trace msg="received accesspoint ping" Oct 29 22:00:36 volumio go-librespot[1456]: time="2025-10-29T22:00:36Z" level=trace msg="received accesspoint pong ack" Oct 29 22:00:36 volumio go-librespot[1456]: time="2025-10-29T22:00:36Z" level=trace msg="sent dealer ping" Oct 29 22:00:36 volumio go-librespot[1456]: time="2025-10-29T22:00:36Z" level=trace msg="received dealer pong" Oct 29 22:00:44 volumio volumio[1190]: info: Oct 29 22:00:44 volumio volumio[1190]: ---------------------------- MPD announces system playlist update Oct 29 22:00:44 volumio volumio[1190]: info: Ignoring MPD Status Update Oct 29 22:00:44 volumio volumio[1190]: info: Oct 29 22:00:44 volumio volumio[1190]: ---------------------------- MPD announces state update: player Oct 29 22:00:44 volumio volumio[1190]: info: ControllerMpd::getState Oct 29 22:00:44 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand status Oct 29 22:00:44 volumio volumio[1190]: info: ------------------------------ 3ms Oct 29 22:00:44 volumio volumio[1190]: info: sendMpdCommand status took 2 milliseconds Oct 29 22:00:44 volumio volumio[1190]: verbose: ControllerMpd::parseState Oct 29 22:00:44 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 22:00:44 volumio volumio[1190]: info: Oct 29 22:00:44 volumio volumio[1190]: ---------------------------- MPD announces system playlist update Oct 29 22:00:44 volumio volumio[1190]: info: Ignoring MPD Status Update Oct 29 22:00:44 volumio volumio[1190]: info: Oct 29 22:00:44 volumio volumio[1190]: ---------------------------- MPD announces state update: player Oct 29 22:00:44 volumio volumio[1190]: info: ControllerMpd::getState Oct 29 22:00:44 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand status Oct 29 22:00:44 volumio volumio[1190]: info: Oct 29 22:00:44 volumio volumio[1190]: ---------------------------- MPD announces system playlist update Oct 29 22:00:44 volumio volumio[1190]: info: Ignoring MPD Status Update Oct 29 22:00:44 volumio volumio[1190]: info: Oct 29 22:00:44 volumio volumio[1190]: ---------------------------- MPD announces state update: player Oct 29 22:00:44 volumio volumio[1190]: info: ControllerMpd::getState Oct 29 22:00:44 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand status Oct 29 22:00:44 volumio volumio[1190]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 29 22:00:44 volumio volumio[1190]: info: ------------------------------ 6ms Oct 29 22:00:44 volumio volumio[1190]: info: sendMpdCommand status took 6 milliseconds Oct 29 22:00:44 volumio volumio[1190]: info: ------------------------------ 4ms Oct 29 22:00:44 volumio volumio[1190]: info: sendMpdCommand status took 3 milliseconds Oct 29 22:00:44 volumio volumio[1190]: verbose: ControllerMpd::parseTrackInfo Oct 29 22:00:44 volumio volumio[1190]: verbose: ControllerMpd::parseState Oct 29 22:00:44 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 22:00:44 volumio volumio[1190]: verbose: ControllerMpd::parseState Oct 29 22:00:44 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 22:00:44 volumio volumio[1190]: info: ControllerMpd::pushState Oct 29 22:00:44 volumio volumio[1190]: info: CoreCommandRouter::servicePushState Oct 29 22:00:44 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Oct 29 22:00:44 volumio volumio[1190]: verbose: STATE SERVICE {"status":"play","position":0,"seek":14856855,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"89 Kbps","isStreaming":false,"title":"Jimmy Smith - Mr. Johnson","artist":"Swiss Jazz","album":null,"uri":"http://stream.srg-ssr.ch/m/rsj/aacp_96","trackType":""} Oct 29 22:00:44 volumio volumio[1190]: verbose: CURRENT POSITION 0 Oct 29 22:00:44 volumio volumio[1190]: info: CoreStateMachine::syncState stateService play Oct 29 22:00:44 volumio volumio[1190]: info: CoreStateMachine::syncState currentStatus play Oct 29 22:00:44 volumio volumio[1190]: info: Received an update from plugin. extracting info from payload Oct 29 22:00:44 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 29 22:00:44 volumio volumio[1190]: info: CoreStateMachine::pushState Oct 29 22:00:44 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 29 22:00:44 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Oct 29 22:00:44 volumio volumio[1190]: info: CoreStateMachine::pushState Oct 29 22:00:44 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Oct 29 22:00:44 volumio volumio[1190]: info: ------------------------------ 21ms Oct 29 22:00:44 volumio volumio[1190]: info: sendMpdCommand playlistinfo took 10 milliseconds Oct 29 22:00:44 volumio volumio[1190]: info: sendMpdCommand playlistinfo took 10 milliseconds Oct 29 22:00:44 volumio volumio[1190]: verbose: ControllerMpd::parseTrackInfo Oct 29 22:00:44 volumio volumio[1190]: verbose: ControllerMpd::parseTrackInfo Oct 29 22:00:44 volumio volumio[1190]: info: ControllerMpd::pushState Oct 29 22:00:44 volumio volumio[1190]: info: CoreCommandRouter::servicePushState Oct 29 22:00:44 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Oct 29 22:00:44 volumio volumio[1190]: verbose: STATE SERVICE {"status":"play","position":0,"seek":14856855,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"89 Kbps","isStreaming":false,"title":"Jimmy Smith - Mr. Johnson","artist":"Swiss Jazz","album":null,"uri":"http://stream.srg-ssr.ch/m/rsj/aacp_96","trackType":""} Oct 29 22:00:44 volumio volumio[1190]: verbose: CURRENT POSITION 0 Oct 29 22:00:44 volumio volumio[1190]: info: CoreStateMachine::syncState stateService play Oct 29 22:00:44 volumio volumio[1190]: info: CoreStateMachine::syncState currentStatus play Oct 29 22:00:44 volumio volumio[1190]: info: Received an update from plugin. extracting info from payload Oct 29 22:00:44 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 29 22:00:44 volumio volumio[1190]: info: CoreStateMachine::pushState Oct 29 22:00:44 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Oct 29 22:00:44 volumio volumio[1190]: info: CoreStateMachine::pushState Oct 29 22:00:44 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Oct 29 22:00:44 volumio volumio[1190]: info: ControllerMpd::pushState Oct 29 22:00:44 volumio volumio[1190]: info: CoreCommandRouter::servicePushState Oct 29 22:00:44 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Oct 29 22:00:44 volumio volumio[1190]: verbose: STATE SERVICE {"status":"play","position":0,"seek":14856855,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"89 Kbps","isStreaming":false,"title":"Jimmy Smith - Mr. Johnson","artist":"Swiss Jazz","album":null,"uri":"http://stream.srg-ssr.ch/m/rsj/aacp_96","trackType":""} Oct 29 22:00:44 volumio volumio[1190]: verbose: CURRENT POSITION 0 Oct 29 22:00:44 volumio volumio[1190]: info: CoreStateMachine::syncState stateService play Oct 29 22:00:44 volumio volumio[1190]: info: CoreStateMachine::syncState currentStatus play Oct 29 22:00:44 volumio volumio[1190]: info: Received an update from plugin. extracting info from payload Oct 29 22:00:44 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 29 22:00:44 volumio volumio[1190]: info: CoreStateMachine::pushState Oct 29 22:00:44 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Oct 29 22:00:44 volumio volumio[1190]: info: CoreStateMachine::pushState Oct 29 22:00:44 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Oct 29 22:00:44 volumio volumio[1190]: info: ------------------------------ 37ms Oct 29 22:00:44 volumio volumio[1190]: info: ------------------------------ 37ms Oct 29 22:00:44 volumio volumio[1190]: info: [LastFM] Split composite title into artist: Jimmy Smith and title: Mr. Johnson Oct 29 22:00:44 volumio volumio[1190]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 29 22:00:44 volumio volumio[1190]: Error: connect ECONNREFUSED 130.211.19.189:80 Oct 29 22:00:44 volumio volumio[1190]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Oct 29 22:00:44 volumio volumio[1190]: errno: -111, Oct 29 22:00:44 volumio volumio[1190]: code: 'ECONNREFUSED', Oct 29 22:00:44 volumio volumio[1190]: syscall: 'connect', Oct 29 22:00:44 volumio volumio[1190]: address: '130.211.19.189', Oct 29 22:00:44 volumio volumio[1190]: port: 80 Oct 29 22:00:44 volumio volumio[1190]: } Oct 29 22:00:44 volumio volumio[1190]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 29 22:00:46 volumio sudo[695]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-29 21:59 Oct 29 22:00:46 volumio sudo[695]: 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="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"