-- Logs begin at Thu 2026-01-08 18:35:07 CET, end at Sat 2026-01-10 15:04:37 CET. -- Jan 10 15:03:03 volumio kernel: hwmon hwmon1: Voltage normalised Jan 10 15:03:06 volumio kernel: hwmon hwmon1: Undervoltage detected! Jan 10 15:03:10 volumio kernel: hwmon hwmon1: Voltage normalised Jan 10 15:03:10 volumio go-librespot[13505]: time="2026-01-10T15:03:10+01:00" level=debug msg="fetched chunk 13/15, size: 524288" uri="spotify:track:0n3N13ifuyUBvSEZlX2Tob" Jan 10 15:03:12 volumio kernel: hwmon hwmon1: Undervoltage detected! Jan 10 15:03:16 volumio kernel: hwmon hwmon1: Voltage normalised Jan 10 15:03:18 volumio kernel: hwmon hwmon1: Undervoltage detected! Jan 10 15:03:22 volumio kernel: hwmon hwmon1: Voltage normalised Jan 10 15:03:23 volumio go-librespot[13505]: time="2026-01-10T15:03:23+01:00" level=trace msg="sent dealer ping" Jan 10 15:03:23 volumio go-librespot[13505]: time="2026-01-10T15:03:23+01:00" level=debug msg="fetched chunk 14/15, size: 524288" uri="spotify:track:0n3N13ifuyUBvSEZlX2Tob" Jan 10 15:03:24 volumio go-librespot[13505]: time="2026-01-10T15:03:24+01:00" level=trace msg="received dealer pong" Jan 10 15:03:24 volumio kernel: hwmon hwmon1: Undervoltage detected! Jan 10 15:03:36 volumio go-librespot[13505]: time="2026-01-10T15:03:36+01:00" level=debug msg="fetched chunk 15/15, size: 256207" uri="spotify:track:0n3N13ifuyUBvSEZlX2Tob" Jan 10 15:03:43 volumio kernel: hwmon hwmon1: Voltage normalised Jan 10 15:03:45 volumio kernel: hwmon hwmon1: Undervoltage detected! Jan 10 15:03:51 volumio kernel: hwmon hwmon1: Voltage normalised Jan 10 15:03:53 volumio go-librespot[13505]: time="2026-01-10T15:03:53+01:00" level=trace msg="sent dealer ping" Jan 10 15:03:54 volumio go-librespot[13505]: time="2026-01-10T15:03:54+01:00" level=trace msg="received dealer pong" Jan 10 15:03:57 volumio go-librespot[13505]: time="2026-01-10T15:03:57+01:00" level=debug msg="prefetching next track" uri="spotify:track:6vfXtcXGx17pRG74SRVGOb" Jan 10 15:03:57 volumio go-librespot[13505]: time="2026-01-10T15:03:57+01:00" level=debug msg="selected format OGG_VORBIS_320 (8619d89e2feaa26c6fef1dbe70894a730de3b5b3)" uri="spotify:track:6vfXtcXGx17pRG74SRVGOb" Jan 10 15:03:57 volumio go-librespot[13505]: time="2026-01-10T15:03:57+01:00" level=debug msg="requested aes key for file 8619d89e2feaa26c6fef1dbe70894a730de3b5b3, gid: 6vfXtcXGx17pRG74SRVGOb" Jan 10 15:03:57 volumio go-librespot[13505]: time="2026-01-10T15:03:57+01:00" level=trace msg="found 2 cdn urls" uri="spotify:track:6vfXtcXGx17pRG74SRVGOb" Jan 10 15:03:57 volumio go-librespot[13505]: time="2026-01-10T15:03:57+01:00" level=debug msg="fetched first chunk of 20, total size is 10190609 bytes" uri="spotify:track:6vfXtcXGx17pRG74SRVGOb" Jan 10 15:03:57 volumio go-librespot[13505]: time="2026-01-10T15:03:57+01:00" level=info msg="prefetched track \"Lofoten\" (duration: 273832ms)" uri="spotify:track:6vfXtcXGx17pRG74SRVGOb" Jan 10 15:03:57 volumio go-librespot[13505]: time="2026-01-10T15:03:57+01:00" level=debug msg="fetched chunk 1/19, size: 524288" uri="spotify:track:6vfXtcXGx17pRG74SRVGOb" Jan 10 15:03:57 volumio go-librespot[13505]: time="2026-01-10T15:03:57+01:00" level=debug msg="fetched chunk 3/19, size: 524288" uri="spotify:track:6vfXtcXGx17pRG74SRVGOb" Jan 10 15:03:57 volumio go-librespot[13505]: time="2026-01-10T15:03:57+01:00" level=debug msg="fetched chunk 2/19, size: 524288" uri="spotify:track:6vfXtcXGx17pRG74SRVGOb" Jan 10 15:03:58 volumio kernel: hwmon hwmon1: Undervoltage detected! Jan 10 15:04:14 volumio kernel: hwmon hwmon1: Voltage normalised Jan 10 15:04:18 volumio kernel: hwmon hwmon1: Undervoltage detected! Jan 10 15:04:23 volumio go-librespot[13505]: time="2026-01-10T15:04:23+01:00" level=trace msg="sent dealer ping" Jan 10 15:04:24 volumio go-librespot[13505]: time="2026-01-10T15:04:24+01:00" level=trace msg="received dealer pong" Jan 10 15:04:26 volumio go-librespot[13505]: time="2026-01-10T15:04:26+01:00" level=trace msg="emitting websocket event: not_playing" Jan 10 15:04:26 volumio go-librespot[13505]: time="2026-01-10T15:04:26+01:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:6vfXtcXGx17pRG74SRVGOb" Jan 10 15:04:26 volumio volumio[13345]: SPOTIFY: received: {"type":"not_playing","data":{"context_uri":"spotify:station:playlist:37i9dQZEVXcC40VV6PYUds","uri":"spotify:track:0n3N13ifuyUBvSEZlX2Tob","play_origin":"playlist"}} Jan 10 15:04:26 volumio volumio[13345]: error: Failed to decode event: not_playing Jan 10 15:04:26 volumio go-librespot[13505]: time="2026-01-10T15:04:26+01:00" level=debug msg="renewing login5 access token" Jan 10 15:04:26 volumio go-librespot[13505]: time="2026-01-10T15:04:26+01:00" level=info msg="authenticated Login5" username="ko***ss" Jan 10 15:04:26 volumio go-librespot[13505]: time="2026-01-10T15:04:26+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 10 15:04:26 volumio go-librespot[13505]: time="2026-01-10T15:04:26+01:00" level=trace msg="emitting websocket event: will_play" Jan 10 15:04:26 volumio go-librespot[13505]: time="2026-01-10T15:04:26+01:00" level=info msg="loaded track \"Lofoten\" (paused: false, position: 0ms, duration: 273832ms, prefetched: true)" uri="spotify:track:6vfXtcXGx17pRG74SRVGOb" Jan 10 15:04:26 volumio volumio[13345]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:station:playlist:37i9dQZEVXcC40VV6PYUds","uri":"spotify:track:6vfXtcXGx17pRG74SRVGOb","play_origin":"playlist"}} Jan 10 15:04:26 volumio go-librespot[13505]: time="2026-01-10T15:04:26+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 10 15:04:26 volumio go-librespot[13505]: time="2026-01-10T15:04:26+01:00" level=trace msg="scheduling prefetch in 244s" Jan 10 15:04:26 volumio go-librespot[13505]: time="2026-01-10T15:04:26+01:00" level=trace msg="emitting websocket event: metadata" Jan 10 15:04:26 volumio volumio[13345]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:6vfXtcXGx17pRG74SRVGOb","name":"Lofoten","artist_names":["RØRE"],"album_name":"Lofoten","album_cover_url":"https://i.scdn.co/image/ab67616d00001e023f5cf889419582692697bead","position":0,"duration":273832,"release_date":"year:2024 month:6 day:21","track_number":1,"disc_number":1}} Jan 10 15:04:26 volumio go-librespot[13505]: time="2026-01-10T15:04:26+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 10 15:04:26 volumio go-librespot[13505]: time="2026-01-10T15:04:26+01:00" level=trace msg="emitting websocket event: playing" Jan 10 15:04:26 volumio volumio[13345]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:station:playlist:37i9dQZEVXcC40VV6PYUds","uri":"spotify:track:6vfXtcXGx17pRG74SRVGOb","resume":false,"play_origin":"playlist"}} Jan 10 15:04:26 volumio volumio[13345]: SPOTIFY: PUSH STATE SPOTIFY Jan 10 15:04:26 volumio volumio[13345]: SPOTIFY: {"status":"play","service":"spop","title":"Lofoten","artist":"RØRE","album":"Lofoten","albumart":"https://i.scdn.co/image/ab67616d00001e023f5cf889419582692697bead","uri":"spotify:track:6vfXtcXGx17pRG74SRVGOb","trackType":"spotify","seek":0,"duration":273,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Jan 10 15:04:26 volumio volumio[13345]: info: CoreCommandRouter::servicePushState Jan 10 15:04:26 volumio volumio[13345]: info: CoreStateMachine::pushState Jan 10 15:04:26 volumio volumio[13345]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 15:04:26 volumio volumio[13345]: info: CoreCommandRouter::volumioPushState Jan 10 15:04:26 volumio volumio[13345]: info: [LastFM] Current track has sufficient metadata: title (Lofoten) and artist (RØRE) passed on explicitly Jan 10 15:04:26 volumio volumio[13345]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30 Jan 10 15:04:26 volumio volumio[13345]: [LastFM] updated "now playing" failed: { Jan 10 15:04:26 volumio volumio[13345]: success: false, Jan 10 15:04:26 volumio volumio[13345]: error: 'Invalid parameters - Your request is missing a required parameter' Jan 10 15:04:26 volumio volumio[13345]: } Jan 10 15:04:27 volumio volumio[13345]: SPOTIFY: PUSH STATE SPOTIFY Jan 10 15:04:27 volumio volumio[13345]: SPOTIFY: {"status":"play","service":"spop","title":"Lofoten","artist":"RØRE","album":"Lofoten","albumart":"https://i.scdn.co/image/ab67616d00001e023f5cf889419582692697bead","uri":"spotify:track:6vfXtcXGx17pRG74SRVGOb","trackType":"spotify","seek":0,"duration":273,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Jan 10 15:04:27 volumio volumio[13345]: info: CoreCommandRouter::servicePushState Jan 10 15:04:27 volumio volumio[13345]: info: CoreStateMachine::pushState Jan 10 15:04:27 volumio volumio[13345]: info: CoreCommandRouter::volumioPushState Jan 10 15:04:27 volumio volumio[13345]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30 Jan 10 15:04:33 volumio kernel: hwmon hwmon1: Voltage normalised Jan 10 15:04:35 volumio kernel: hwmon hwmon1: Undervoltage detected! Jan 10 15:04:35 volumio volumio[13345]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 10 15:04:35 volumio volumio[13345]: TypeError: Cannot read property '@' of undefined Jan 10 15:04:35 volumio volumio[13345]: at Object.callback (/data/plugins/user_interface/lastfm/node_modules/simple-lastfm/lib/index.js:342:14) Jan 10 15:04:35 volumio volumio[13345]: at /data/plugins/user_interface/lastfm/node_modules/simple-lastfm/lib/index.js:60:13 Jan 10 15:04:35 volumio volumio[13345]: at Parser. (/data/plugins/user_interface/lastfm/node_modules/xml2js/lib/parser.js:308:18) Jan 10 15:04:35 volumio volumio[13345]: at Parser.emit (events.js:315:20) Jan 10 15:04:35 volumio volumio[13345]: at SAXParser.onerror (/data/plugins/user_interface/lastfm/node_modules/xml2js/lib/parser.js:123:26) Jan 10 15:04:35 volumio volumio[13345]: at emit (/data/plugins/user_interface/lastfm/node_modules/sax/lib/sax.js:624:35) Jan 10 15:04:35 volumio volumio[13345]: at error (/data/plugins/user_interface/lastfm/node_modules/sax/lib/sax.js:653:5) Jan 10 15:04:35 volumio volumio[13345]: at strictFail (/data/plugins/user_interface/lastfm/node_modules/sax/lib/sax.js:677:7) Jan 10 15:04:35 volumio volumio[13345]: at closeTag (/data/plugins/user_interface/lastfm/node_modules/sax/lib/sax.js:871:9) Jan 10 15:04:35 volumio volumio[13345]: at SAXParser.write (/data/plugins/user_interface/lastfm/node_modules/sax/lib/sax.js:1436:13) Jan 10 15:04:35 volumio volumio[13345]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 10 15:04:37 volumio sudo[2168]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-01-10 15:03 Jan 10 15:04:37 volumio sudo[2168]: 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="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 05:43:24 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="15d0241b0cd805792809f3ddf4a0fc4e"