-- Logs begin at Wed 2024-04-24 11:38:14 CEST, end at Wed 2024-04-24 12:00:18 CEST. -- Apr 24 11:59:04 retaurcia.cpl go-librespot[1310]: time="2024-04-24T11:59:04+02:00" level=debug msg="fetched chunk 4/21, size: 524288" Apr 24 11:59:08 retaurcia.cpl go-librespot[1310]: time="2024-04-24T11:59:08+02:00" level=debug msg="handling update_context player command from e4b03bd868a6bc1f29e3c3d94b4c57ca966619dc" Apr 24 11:59:09 retaurcia.cpl go-librespot[1310]: time="2024-04-24T11:59:09+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 24 11:59:09 retaurcia.cpl go-librespot[1310]: time="2024-04-24T11:59:09+02:00" level=debug msg="sending successful reply for delaer request" Apr 24 11:59:14 retaurcia.cpl go-librespot[1310]: time="2024-04-24T11:59:14+02:00" level=debug msg="handling skip_next player command from e4b03bd868a6bc1f29e3c3d94b4c57ca966619dc" Apr 24 11:59:14 retaurcia.cpl go-librespot[1310]: time="2024-04-24T11:59:14+02:00" level=debug msg="skip next track" Apr 24 11:59:14 retaurcia.cpl go-librespot[1310]: time="2024-04-24T11:59:14+02:00" level=debug msg="loading track spotify:track:1hGy2eLcmC8eKx7qr1tOqx (paused: false, position: 77ms)" Apr 24 11:59:14 retaurcia.cpl go-librespot[1310]: time="2024-04-24T11:59:14+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 24 11:59:14 retaurcia.cpl go-librespot[1310]: time="2024-04-24T11:59:14+02:00" level=trace msg="emitting websocket event: will_play" Apr 24 11:59:14 retaurcia.cpl volumio[920]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:1hGy2eLcmC8eKx7qr1tOqx","play_origin":"playlist/ondemand"}} Apr 24 11:59:14 retaurcia.cpl go-librespot[1310]: time="2024-04-24T11:59:14+02:00" level=debug msg="selected format OGG_VORBIS_320 for spotify:track:1hGy2eLcmC8eKx7qr1tOqx" Apr 24 11:59:14 retaurcia.cpl go-librespot[1310]: time="2024-04-24T11:59:14+02:00" level=debug msg="requested aes key for file 1c335c4e6ea1cec6fabd19d5efc8866c9bc208b4, gid: 1hGy2eLcmC8eKx7qr1tOqx" Apr 24 11:59:14 retaurcia.cpl go-librespot[1310]: time="2024-04-24T11:59:14+02:00" level=debug msg="fetched first chunk of 18, total size is 9008260 bytes" Apr 24 11:59:14 retaurcia.cpl go-librespot[1310]: time="2024-04-24T11:59:14+02:00" level=trace msg="seek to 77ms (diff: 41ms, samples: 3395, bytes: 3465)" Apr 24 11:59:14 retaurcia.cpl volumio[920]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Apr 24 11:59:14 retaurcia.cpl volumio[920]: info: FusionDsp - ---- read samplerate from file: 44100 Apr 24 11:59:14 retaurcia.cpl volumio[920]: error: FusionDsp - File size not found in array! Apr 24 11:59:14 retaurcia.cpl volumio[920]: info: FusionDsp - Nulleq applied Apr 24 11:59:14 retaurcia.cpl go-librespot[1310]: time="2024-04-24T11:59:14+02:00" level=info msg="loaded track \"Beautiful Girls\" (uri: spotify:track:1hGy2eLcmC8eKx7qr1tOqx, paused: false, position: 77ms, duration: 225373ms)" Apr 24 11:59:14 retaurcia.cpl volumio[920]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Apr 24 11:59:14 retaurcia.cpl volumio[920]: info: FusionDsp - ---- read samplerate from file: 44100 Apr 24 11:59:14 retaurcia.cpl volumio[920]: error: FusionDsp - File size not found in array! Apr 24 11:59:14 retaurcia.cpl volumio[920]: info: FusionDsp - Nulleq applied Apr 24 11:59:14 retaurcia.cpl volumio[920]: info: FusionDsp - {"Reload":{"result":"Ok"}} Apr 24 11:59:14 retaurcia.cpl volumio[920]: info: FusionDsp - {"Reload":{"result":"Ok"}} Apr 24 11:59:15 retaurcia.cpl go-librespot[1310]: time="2024-04-24T11:59:15+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 24 11:59:15 retaurcia.cpl go-librespot[1310]: time="2024-04-24T11:59:15+02:00" level=trace msg="emitting websocket event: metadata" Apr 24 11:59:15 retaurcia.cpl volumio[920]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:1hGy2eLcmC8eKx7qr1tOqx","name":"Beautiful Girls","artist_names":["Sean Kingston"],"album_name":"Beautiful Girls","album_cover_url":"https://i.scdn.co/image/ab67616d00001e026a45d20e414fbc456ecea553","position":77,"duration":225373,"release_date":"year:2007 month:7 day:23","track_number":1,"disc_number":1}} Apr 24 11:59:15 retaurcia.cpl go-librespot[1310]: time="2024-04-24T11:59:15+02:00" level=debug msg="sending successful reply for delaer request" Apr 24 11:59:15 retaurcia.cpl go-librespot[1310]: time="2024-04-24T11:59:15+02:00" level=debug msg="fetched chunk 3/17, size: 524288" Apr 24 11:59:15 retaurcia.cpl go-librespot[1310]: time="2024-04-24T11:59:15+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 24 11:59:15 retaurcia.cpl go-librespot[1310]: time="2024-04-24T11:59:15+02:00" level=trace msg="emitting websocket event: playing" Apr 24 11:59:15 retaurcia.cpl go-librespot[1310]: time="2024-04-24T11:59:15+02:00" level=debug msg="handling update_context player command from e4b03bd868a6bc1f29e3c3d94b4c57ca966619dc" Apr 24 11:59:15 retaurcia.cpl volumio[920]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:1hGy2eLcmC8eKx7qr1tOqx","play_origin":"playlist/ondemand"}} Apr 24 11:59:15 retaurcia.cpl volumio[920]: SPOTIFY: PUSH STATE SPOTIFY Apr 24 11:59:15 retaurcia.cpl volumio[920]: SPOTIFY: {"status":"play","service":"spop","title":"Beautiful Girls","artist":"Sean Kingston","album":"Beautiful Girls","albumart":"https://i.scdn.co/image/ab67616d00001e026a45d20e414fbc456ecea553","uri":"spotify:track:1hGy2eLcmC8eKx7qr1tOqx","trackType":"spotify","seek":77,"duration":225,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Apr 24 11:59:15 retaurcia.cpl volumio[920]: info: CoreCommandRouter::servicePushState Apr 24 11:59:15 retaurcia.cpl volumio[920]: info: CoreStateMachine::pushState Apr 24 11:59:15 retaurcia.cpl volumio[920]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 11:59:15 retaurcia.cpl volumio[920]: info: CoreCommandRouter::volumioPushState Apr 24 11:59:15 retaurcia.cpl volumio[920]: info: [LastFM] Current track has sufficient metadata: title (Beautiful Girls) and artist (Sean Kingston) passed on explicitly Apr 24 11:59:15 retaurcia.cpl volumio[920]: error: [LastFM] plugin is not authenticated, please retry, or track info could not be determined (very rare) Apr 24 11:59:15 retaurcia.cpl volumio[920]: SPOTIFY: RECEIVED VOLUMIO VOLUME 68 Apr 24 11:59:15 retaurcia.cpl go-librespot[1310]: time="2024-04-24T11:59:15+02:00" level=debug msg="fetched chunk 2/17, size: 524288" Apr 24 11:59:15 retaurcia.cpl go-librespot[1310]: time="2024-04-24T11:59:15+02:00" level=debug msg="fetched chunk 1/17, size: 524288" Apr 24 11:59:15 retaurcia.cpl go-librespot[1310]: time="2024-04-24T11:59:15+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 24 11:59:15 retaurcia.cpl go-librespot[1310]: time="2024-04-24T11:59:15+02:00" level=debug msg="sending successful reply for delaer request" Apr 24 11:59:15 retaurcia.cpl volumio[920]: info: camilladsp spawned new process with pid 2350, instance 1, run: true Apr 24 11:59:15 retaurcia.cpl volumio[920]: SPOTIFY: PUSH STATE SPOTIFY Apr 24 11:59:15 retaurcia.cpl volumio[920]: SPOTIFY: {"status":"play","service":"spop","title":"Beautiful Girls","artist":"Sean Kingston","album":"Beautiful Girls","albumart":"https://i.scdn.co/image/ab67616d00001e026a45d20e414fbc456ecea553","uri":"spotify:track:1hGy2eLcmC8eKx7qr1tOqx","trackType":"spotify","seek":1077,"duration":225,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Apr 24 11:59:15 retaurcia.cpl volumio[920]: info: CoreCommandRouter::servicePushState Apr 24 11:59:15 retaurcia.cpl volumio[920]: info: CoreStateMachine::pushState Apr 24 11:59:15 retaurcia.cpl volumio[920]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 11:59:15 retaurcia.cpl volumio[920]: info: CoreCommandRouter::volumioPushState Apr 24 11:59:15 retaurcia.cpl volumio[920]: SPOTIFY: RECEIVED VOLUMIO VOLUME 68 Apr 24 11:59:27 retaurcia.cpl go-librespot[1310]: time="2024-04-24T11:59:27+02:00" level=debug msg="fetched chunk 4/17, size: 524288" Apr 24 11:59:41 retaurcia.cpl go-librespot[1310]: time="2024-04-24T11:59:41+02:00" level=debug msg="fetched chunk 5/17, size: 524288" Apr 24 11:59:53 retaurcia.cpl go-librespot[1310]: time="2024-04-24T11:59:53+02:00" level=debug msg="fetched chunk 6/17, size: 524288" Apr 24 12:00:06 retaurcia.cpl go-librespot[1310]: time="2024-04-24T12:00:06+02:00" level=debug msg="fetched chunk 7/17, size: 524288" Apr 24 12:00:12 retaurcia.cpl volumio[920]: info: [1713952812805] [80s80s] Pushing the next song state Grace Jones - Pull Up To The Bumper and getting next track. Apr 24 12:00:12 retaurcia.cpl volumio[920]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 24 12:00:12 retaurcia.cpl volumio[920]: TypeError: Cannot set property 'name' of undefined Apr 24 12:00:12 retaurcia.cpl volumio[920]: at Controller80s80s.pushSongState (/data/plugins/music_service/80s80s/index.js:565:20) Apr 24 12:00:12 retaurcia.cpl volumio[920]: at Controller80s80s.playNextTrack (/data/plugins/music_service/80s80s/index.js:665:34) Apr 24 12:00:12 retaurcia.cpl volumio[920]: at NanoTimer.setTimeout (/data/plugins/music_service/80s80s/node_modules/nanotimer/lib/nanotimer.js:343:9) Apr 24 12:00:12 retaurcia.cpl volumio[920]: at Immediate. (/data/plugins/music_service/80s80s/node_modules/nanotimer/lib/nanotimer.js:327:66) Apr 24 12:00:12 retaurcia.cpl volumio[920]: at processImmediate (internal/timers.js:461:21) Apr 24 12:00:12 retaurcia.cpl volumio[920]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 24 12:00:18 retaurcia.cpl sudo[2420]: volumio : unable to resolve host retaurcia.cpl Apr 24 12:00:18 retaurcia.cpl sudo[2420]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 24 12:00:18 retaurcia.cpl sudo[2420]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-24 11:59 Apr 24 12:00:18 retaurcia.cpl sudo[2420]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"