-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Wed 2025-07-16 15:08:34 UTC. -- Jul 16 15:07:05 volumio go-librespot[1330]: time="2025-07-16T15:07:05Z" level=debug msg="fetched chunk 7/26, size: 524288" uri="spotify:track:17bpU2xSlJFRLY3KVb7Sou" Jul 16 15:07:18 volumio go-librespot[1330]: time="2025-07-16T15:07:18Z" level=debug msg="fetched chunk 8/26, size: 524288" uri="spotify:track:17bpU2xSlJFRLY3KVb7Sou" Jul 16 15:07:31 volumio go-librespot[1330]: time="2025-07-16T15:07:31Z" level=debug msg="fetched chunk 9/26, size: 524288" uri="spotify:track:17bpU2xSlJFRLY3KVb7Sou" Jul 16 15:07:36 volumio go-librespot[1330]: time="2025-07-16T15:07:36Z" level=debug msg="handling skip_next player command from 0e04f75f70b92ef6a3cbbc83d804a674cc4959cf" Jul 16 15:07:36 volumio go-librespot[1330]: time="2025-07-16T15:07:36Z" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:3dTGR2oQA1XaC850o5oPdK" Jul 16 15:07:36 volumio go-librespot[1330]: time="2025-07-16T15:07:36Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 16 15:07:36 volumio go-librespot[1330]: time="2025-07-16T15:07:36Z" level=trace msg="emitting websocket event: will_play" Jul 16 15:07:36 volumio volumio[1029]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:3dTGR2oQA1XaC850o5oPdK","play_origin":"playlist"}} Jul 16 15:07:36 volumio go-librespot[1330]: time="2025-07-16T15:07:36Z" level=debug msg="selected format OGG_VORBIS_320 (9dd96da0ff365a335b46d43af791f03c90189682)" uri="spotify:track:3dTGR2oQA1XaC850o5oPdK" Jul 16 15:07:36 volumio go-librespot[1330]: time="2025-07-16T15:07:36Z" level=debug msg="requested aes key for file 9dd96da0ff365a335b46d43af791f03c90189682, gid: 3dTGR2oQA1XaC850o5oPdK" Jul 16 15:07:36 volumio go-librespot[1330]: time="2025-07-16T15:07:36Z" level=debug msg="fetched first chunk of 39, total size is 20388088 bytes" uri="spotify:track:3dTGR2oQA1XaC850o5oPdK" Jul 16 15:07:36 volumio go-librespot[1330]: time="2025-07-16T15:07:36Z" level=trace msg="seek to 0ms (diff: -885ms, samples: 0, bytes: 51934)" uri="spotify:track:3dTGR2oQA1XaC850o5oPdK" Jul 16 15:07:36 volumio go-librespot[1330]: time="2025-07-16T15:07:36Z" level=info msg="loaded track \"Hotel California\" (paused: false, position: 0ms, duration: 432066ms, prefetched: false)" uri="spotify:track:3dTGR2oQA1XaC850o5oPdK" Jul 16 15:07:36 volumio go-librespot[1330]: time="2025-07-16T15:07:36Z" level=debug msg="fetched chunk 2/38, size: 524288" uri="spotify:track:3dTGR2oQA1XaC850o5oPdK" Jul 16 15:07:36 volumio go-librespot[1330]: time="2025-07-16T15:07:36Z" level=debug msg="fetched chunk 1/38, size: 524288" uri="spotify:track:3dTGR2oQA1XaC850o5oPdK" Jul 16 15:07:36 volumio go-librespot[1330]: time="2025-07-16T15:07:36Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 16 15:07:36 volumio go-librespot[1330]: time="2025-07-16T15:07:36Z" level=debug msg="fetched chunk 3/38, size: 524288" uri="spotify:track:3dTGR2oQA1XaC850o5oPdK" Jul 16 15:07:36 volumio go-librespot[1330]: time="2025-07-16T15:07:36Z" level=trace msg="scheduling prefetch in 401s" Jul 16 15:07:36 volumio go-librespot[1330]: time="2025-07-16T15:07:36Z" level=trace msg="emitting websocket event: metadata" Jul 16 15:07:36 volumio go-librespot[1330]: time="2025-07-16T15:07:36Z" level=debug msg="sending successful reply for dealer request" Jul 16 15:07:36 volumio volumio[1029]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:3dTGR2oQA1XaC850o5oPdK","name":"Hotel California","artist_names":["Eagles"],"album_name":"Hell Freezes Over","album_cover_url":"https://i.scdn.co/image/ab67616d00001e022d1eaba068fdfe2a09e7ff9e","position":0,"duration":432066,"release_date":"year:1994 month:1 day:1","track_number":6,"disc_number":1}} Jul 16 15:07:37 volumio go-librespot[1330]: time="2025-07-16T15:07:37Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 16 15:07:37 volumio go-librespot[1330]: time="2025-07-16T15:07:37Z" level=trace msg="emitting websocket event: playing" Jul 16 15:07:37 volumio volumio[1029]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:3dTGR2oQA1XaC850o5oPdK","play_origin":"playlist"}} Jul 16 15:07:37 volumio volumio[1029]: SPOTIFY: PUSH STATE SPOTIFY Jul 16 15:07:37 volumio volumio[1029]: SPOTIFY: {"status":"play","service":"spop","title":"Hotel California","artist":"Eagles","album":"Hell Freezes Over","albumart":"https://i.scdn.co/image/ab67616d00001e022d1eaba068fdfe2a09e7ff9e","uri":"spotify:track:3dTGR2oQA1XaC850o5oPdK","trackType":"spotify","seek":0,"duration":432,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":true,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Jul 16 15:07:37 volumio volumio[1029]: info: CoreCommandRouter::servicePushState Jul 16 15:07:37 volumio volumio[1029]: info: CoreStateMachine::pushState Jul 16 15:07:37 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 16 15:07:37 volumio volumio[1029]: info: CoreCommandRouter::volumioPushState Jul 16 15:07:37 volumio volumio[1029]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 16 15:07:37 volumio volumio[1029]: SPOTIFY: PUSH STATE SPOTIFY Jul 16 15:07:37 volumio volumio[1029]: SPOTIFY: {"status":"play","service":"spop","title":"Hotel California","artist":"Eagles","album":"Hell Freezes Over","albumart":"https://i.scdn.co/image/ab67616d00001e022d1eaba068fdfe2a09e7ff9e","uri":"spotify:track:3dTGR2oQA1XaC850o5oPdK","trackType":"spotify","seek":0,"duration":432,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":true,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Jul 16 15:07:37 volumio volumio[1029]: info: CoreCommandRouter::servicePushState Jul 16 15:07:37 volumio volumio[1029]: info: CoreStateMachine::pushState Jul 16 15:07:37 volumio volumio[1029]: info: CoreCommandRouter::volumioPushState Jul 16 15:07:37 volumio volumio[1029]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 16 15:07:46 volumio go-librespot[1330]: time="2025-07-16T15:07:46Z" level=debug msg="fetched chunk 4/38, size: 524288" uri="spotify:track:3dTGR2oQA1XaC850o5oPdK" Jul 16 15:07:58 volumio go-librespot[1330]: time="2025-07-16T15:07:58Z" level=debug msg="fetched chunk 5/38, size: 524288" uri="spotify:track:3dTGR2oQA1XaC850o5oPdK" Jul 16 15:08:08 volumio go-librespot[1330]: time="2025-07-16T15:08:08Z" level=debug msg="fetched chunk 6/38, size: 524288" uri="spotify:track:3dTGR2oQA1XaC850o5oPdK" Jul 16 15:08:20 volumio go-librespot[1330]: time="2025-07-16T15:08:20Z" level=debug msg="fetched chunk 7/38, size: 524288" uri="spotify:track:3dTGR2oQA1XaC850o5oPdK" Jul 16 15:08:31 volumio go-librespot[1330]: time="2025-07-16T15:08:31Z" level=debug msg="fetched chunk 8/38, size: 524288" uri="spotify:track:3dTGR2oQA1XaC850o5oPdK" Jul 16 15:08:33 volumio volumio[1029]: info: [1752678513825] [80s80s] Pushing the next song state Simply Red - Come to My Aid and getting next track. Jul 16 15:08:33 volumio volumio[1029]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 16 15:08:33 volumio volumio[1029]: TypeError: Cannot set property 'name' of undefined Jul 16 15:08:33 volumio volumio[1029]: at Controller80s80s.pushSongState (/data/plugins/music_service/80s80s/index.js:565:20) Jul 16 15:08:33 volumio volumio[1029]: at Controller80s80s.playNextTrack (/data/plugins/music_service/80s80s/index.js:665:34) Jul 16 15:08:33 volumio volumio[1029]: at NanoTimer.setTimeout (/data/plugins/music_service/80s80s/node_modules/nanotimer/lib/nanotimer.js:343:9) Jul 16 15:08:33 volumio volumio[1029]: at Immediate. (/data/plugins/music_service/80s80s/node_modules/nanotimer/lib/nanotimer.js:327:66) Jul 16 15:08:33 volumio volumio[1029]: at processImmediate (internal/timers.js:461:21) Jul 16 15:08:33 volumio volumio[1029]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 16 15:08:34 volumio sudo[18199]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-16 15:07 Jul 16 15:08:34 volumio sudo[18199]: 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 06:51:56 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b9009e0280f50389e80a537357ff3961"