-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Wed 2025-03-12 18:17:52 CET. -- Mar 12 18:16:08 salon go-librespot[1206]: time="2025-03-12T18:16:08+01:00" level=debug msg="fetched chunk 12/18, size: 524288" uri="spotify:track:2F7F4ZWiPnJ8ZUK2Gc96dQ" Mar 12 18:16:19 salon go-librespot[1206]: time="2025-03-12T18:16:19+01:00" level=debug msg="fetched chunk 13/18, size: 524288" uri="spotify:track:2F7F4ZWiPnJ8ZUK2Gc96dQ" Mar 12 18:16:30 salon go-librespot[1206]: time="2025-03-12T18:16:30+01:00" level=debug msg="fetched chunk 14/18, size: 524288" uri="spotify:track:2F7F4ZWiPnJ8ZUK2Gc96dQ" Mar 12 18:16:41 salon go-librespot[1206]: time="2025-03-12T18:16:41+01:00" level=debug msg="fetched chunk 15/18, size: 524288" uri="spotify:track:2F7F4ZWiPnJ8ZUK2Gc96dQ" Mar 12 18:16:51 salon go-librespot[1206]: time="2025-03-12T18:16:51+01:00" level=debug msg="fetched chunk 16/18, size: 524288" uri="spotify:track:2F7F4ZWiPnJ8ZUK2Gc96dQ" Mar 12 18:17:02 salon go-librespot[1206]: time="2025-03-12T18:17:02+01:00" level=debug msg="fetched chunk 17/18, size: 524288" uri="spotify:track:2F7F4ZWiPnJ8ZUK2Gc96dQ" Mar 12 18:17:12 salon go-librespot[1206]: time="2025-03-12T18:17:12+01:00" level=debug msg="fetched chunk 18/18, size: 130880" uri="spotify:track:2F7F4ZWiPnJ8ZUK2Gc96dQ" Mar 12 18:17:19 salon go-librespot[1206]: time="2025-03-12T18:17:19+01:00" level=debug msg="prefetching next track" uri="spotify:track:31a8CmGI9jsHtLYngqhtTW" Mar 12 18:17:19 salon go-librespot[1206]: time="2025-03-12T18:17:19+01:00" level=debug msg="selected format OGG_VORBIS_320 (8d7797a0e98c2f8110d4cede0f110eb50c86b7b7)" uri="spotify:track:31a8CmGI9jsHtLYngqhtTW" Mar 12 18:17:19 salon go-librespot[1206]: time="2025-03-12T18:17:19+01:00" level=debug msg="requested aes key for file 8d7797a0e98c2f8110d4cede0f110eb50c86b7b7, gid: 31a8CmGI9jsHtLYngqhtTW" Mar 12 18:17:19 salon go-librespot[1206]: time="2025-03-12T18:17:19+01:00" level=debug msg="fetched first chunk of 14, total size is 6859792 bytes" uri="spotify:track:31a8CmGI9jsHtLYngqhtTW" Mar 12 18:17:19 salon go-librespot[1206]: time="2025-03-12T18:17:19+01:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" uri="spotify:track:31a8CmGI9jsHtLYngqhtTW" Mar 12 18:17:19 salon go-librespot[1206]: time="2025-03-12T18:17:19+01:00" level=info msg="prefetched track \"C'est l'heure\" (duration: 149493ms)" uri="spotify:track:31a8CmGI9jsHtLYngqhtTW" Mar 12 18:17:19 salon go-librespot[1206]: time="2025-03-12T18:17:19+01:00" level=debug msg="fetched chunk 1/13, size: 524288" uri="spotify:track:31a8CmGI9jsHtLYngqhtTW" Mar 12 18:17:19 salon go-librespot[1206]: time="2025-03-12T18:17:19+01:00" level=debug msg="fetched chunk 2/13, size: 524288" uri="spotify:track:31a8CmGI9jsHtLYngqhtTW" Mar 12 18:17:19 salon go-librespot[1206]: time="2025-03-12T18:17:19+01:00" level=debug msg="fetched chunk 3/13, size: 524288" uri="spotify:track:31a8CmGI9jsHtLYngqhtTW" Mar 12 18:17:47 salon go-librespot[1206]: time="2025-03-12T18:17:47+01:00" level=trace msg="emitting websocket event: not_playing" Mar 12 18:17:47 salon go-librespot[1206]: time="2025-03-12T18:17:47+01:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:31a8CmGI9jsHtLYngqhtTW" Mar 12 18:17:47 salon volumio[977]: SPOTIFY: received: {"type":"not_playing","data":{"uri":"spotify:track:2F7F4ZWiPnJ8ZUK2Gc96dQ","play_origin":"album"}} Mar 12 18:17:47 salon volumio[977]: error: Failed to decode event: not_playing Mar 12 18:17:47 salon go-librespot[1206]: time="2025-03-12T18:17:47+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 12 18:17:47 salon go-librespot[1206]: time="2025-03-12T18:17:47+01:00" level=trace msg="emitting websocket event: will_play" Mar 12 18:17:47 salon volumio[977]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:31a8CmGI9jsHtLYngqhtTW","play_origin":"album"}} Mar 12 18:17:47 salon go-librespot[1206]: time="2025-03-12T18:17:47+01:00" level=info msg="loaded track \"C'est l'heure\" (paused: false, position: 0ms, duration: 149493ms, prefetched: true)" uri="spotify:track:31a8CmGI9jsHtLYngqhtTW" Mar 12 18:17:47 salon go-librespot[1206]: time="2025-03-12T18:17:47+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 12 18:17:47 salon go-librespot[1206]: time="2025-03-12T18:17:47+01:00" level=trace msg="scheduling prefetch in 119s" Mar 12 18:17:47 salon go-librespot[1206]: time="2025-03-12T18:17:47+01:00" level=trace msg="emitting websocket event: metadata" Mar 12 18:17:47 salon volumio[977]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:31a8CmGI9jsHtLYngqhtTW","name":"C'est l'heure","artist_names":["Ben Mazué"],"album_name":"Famille","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02be093b53d9c1d7f890bffc4e","position":0,"duration":149493,"release_date":"year:2025 month:2 day:28","track_number":2,"disc_number":1}} Mar 12 18:17:47 salon go-librespot[1206]: time="2025-03-12T18:17:47+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 12 18:17:47 salon go-librespot[1206]: time="2025-03-12T18:17:47+01:00" level=trace msg="emitting websocket event: playing" Mar 12 18:17:47 salon volumio[977]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:31a8CmGI9jsHtLYngqhtTW","play_origin":"album"}} Mar 12 18:17:47 salon volumio[977]: SPOTIFY: PUSH STATE SPOTIFY Mar 12 18:17:47 salon volumio[977]: SPOTIFY: {"status":"play","service":"spop","title":"C'est l'heure","artist":"Ben Mazué","album":"Famille","albumart":"https://i.scdn.co/image/ab67616d00001e02be093b53d9c1d7f890bffc4e","uri":"spotify:track:31a8CmGI9jsHtLYngqhtTW","trackType":"spotify","seek":0,"duration":149,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Mar 12 18:17:47 salon volumio[977]: info: CoreCommandRouter::servicePushState Mar 12 18:17:47 salon volumio[977]: info: CoreStateMachine::pushState Mar 12 18:17:47 salon volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 12 18:17:47 salon volumio[977]: info: CoreCommandRouter::volumioPushState Mar 12 18:17:47 salon volumio[977]: info: MRS: Pushing multiroomSync output update for this device Mar 12 18:17:47 salon volumio[977]: info: MRS: Pushing multiroomSync output Mar 12 18:17:47 salon volumio[977]: info: [LastFM] Current track has sufficient metadata: title (C'est l'heure) and artist (Ben Mazué) passed on explicitly Mar 12 18:17:47 salon volumio[977]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 12 18:17:48 salon volumio[977]: SPOTIFY: PUSH STATE SPOTIFY Mar 12 18:17:48 salon volumio[977]: SPOTIFY: {"status":"play","service":"spop","title":"C'est l'heure","artist":"Ben Mazué","album":"Famille","albumart":"https://i.scdn.co/image/ab67616d00001e02be093b53d9c1d7f890bffc4e","uri":"spotify:track:31a8CmGI9jsHtLYngqhtTW","trackType":"spotify","seek":1000,"duration":149,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Mar 12 18:17:48 salon volumio[977]: info: CoreCommandRouter::servicePushState Mar 12 18:17:48 salon volumio[977]: info: CoreStateMachine::pushState Mar 12 18:17:48 salon volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 12 18:17:48 salon volumio[977]: info: CoreCommandRouter::volumioPushState Mar 12 18:17:48 salon volumio[977]: info: MRS: Pushing multiroomSync output update for this device Mar 12 18:17:48 salon volumio[977]: info: MRS: Pushing multiroomSync output Mar 12 18:17:48 salon volumio[977]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 12 18:17:51 salon volumio[977]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 12 18:17:51 salon volumio[977]: TypeError: Cannot read property '@' of null Mar 12 18:17:51 salon volumio[977]: at Object.callback (/data/plugins/user_interface/lastfm/node_modules/simple-lastfm/lib/index.js:342:14) Mar 12 18:17:51 salon volumio[977]: at /data/plugins/user_interface/lastfm/node_modules/simple-lastfm/lib/index.js:60:13 Mar 12 18:17:51 salon volumio[977]: at Parser. (/data/plugins/user_interface/lastfm/node_modules/xml2js/lib/parser.js:304:18) Mar 12 18:17:51 salon volumio[977]: at Parser.emit (events.js:315:20) Mar 12 18:17:51 salon volumio[977]: at Parser.exports.Parser.Parser.parseString (/data/plugins/user_interface/lastfm/node_modules/xml2js/lib/parser.js:314:16) Mar 12 18:17:51 salon volumio[977]: at Parser.parseString (/data/plugins/user_interface/lastfm/node_modules/xml2js/lib/parser.js:5:59) Mar 12 18:17:51 salon volumio[977]: at IncomingMessage. (/data/plugins/user_interface/lastfm/node_modules/simple-lastfm/lib/index.js:58:14) Mar 12 18:17:51 salon volumio[977]: at IncomingMessage.emit (events.js:327:22) Mar 12 18:17:51 salon volumio[977]: at endReadableNT (internal/streams/readable.js:1327:12) Mar 12 18:17:51 salon volumio[977]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Mar 12 18:17:51 salon volumio[977]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 12 18:17:52 salon sudo[20889]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-12 18:16 Mar 12 18:17:52 salon sudo[20889]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="a01a833dd28483f88cb390596f5090b7187992af" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 27 Feb 2025 11:42:54 AM CET" VOLUMIO_VERSION="3.795" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4049a67f1c09d4a94e033ba35a80a144"