-- 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"