-- Logs begin at Tue 2024-08-06 03:26:01 UTC, end at Fri 2024-08-23 15:11:29 UTC. --
Aug 23 15:10:03 volumio go-librespot[1086]: time="2024-08-23T15:10:03Z" level=debug msg="fetched chunk 13/16, size: 524288"
Aug 23 15:10:16 volumio go-librespot[1086]: time="2024-08-23T15:10:16Z" level=debug msg="fetched chunk 14/16, size: 524288"
Aug 23 15:10:28 volumio go-librespot[1086]: time="2024-08-23T15:10:28Z" level=debug msg="fetched chunk 15/16, size: 524288"
Aug 23 15:10:40 volumio go-librespot[1086]: time="2024-08-23T15:10:40Z" level=debug msg="fetched chunk 16/16, size: 290604"
Aug 23 15:11:28 volumio go-librespot[1086]: time="2024-08-23T15:11:28Z" level=trace msg="emitting websocket event: not_playing"
Aug 23 15:11:28 volumio go-librespot[1086]: time="2024-08-23T15:11:28Z" level=debug msg="loading track spotify:track:4LRxmh62YHxSHL4hGLq0ln (paused: false, position: 1ms)"
Aug 23 15:11:28 volumio volumio[909]: SPOTIFY: received: {"type":"not_playing","data":{"uri":"spotify:track:58spuRyMUsjKHQHEGwLC99","play_origin":"playlist"}}
Aug 23 15:11:28 volumio volumio[909]: error: Failed to decode event: not_playing
Aug 23 15:11:28 volumio go-librespot[1086]: time="2024-08-23T15:11:28Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED"
Aug 23 15:11:28 volumio go-librespot[1086]: time="2024-08-23T15:11:28Z" level=trace msg="emitting websocket event: will_play"
Aug 23 15:11:28 volumio volumio[909]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:4LRxmh62YHxSHL4hGLq0ln","play_origin":"playlist"}}
Aug 23 15:11:28 volumio go-librespot[1086]: time="2024-08-23T15:11:28Z" level=debug msg="selected format OGG_VORBIS_320 for spotify:track:4LRxmh62YHxSHL4hGLq0ln"
Aug 23 15:11:28 volumio go-librespot[1086]: time="2024-08-23T15:11:28Z" level=debug msg="requested aes key for file 4646232cc2622d26f0c5cdf37e592b1f5c42942e, gid: 4LRxmh62YHxSHL4hGLq0ln"
Aug 23 15:11:28 volumio go-librespot[1086]: time="2024-08-23T15:11:28Z" level=debug msg="fetched first chunk of 20, total size is 10279312 bytes"
Aug 23 15:11:28 volumio go-librespot[1086]: time="2024-08-23T15:11:28Z" level=trace msg="seek to 1ms (diff: 1ms, samples: 44, bytes: 0)"
Aug 23 15:11:28 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2
Aug 23 15:11:28 volumio go-librespot[1086]: time="2024-08-23T15:11:28Z" level=info msg="loaded track \"Island\" (uri: spotify:track:4LRxmh62YHxSHL4hGLq0ln, paused: false, position: 1ms, duration: 239573ms)"
Aug 23 15:11:28 volumio go-librespot[1086]: time="2024-08-23T15:11:28Z" level=debug msg="fetched chunk 2/19, size: 524288"
Aug 23 15:11:28 volumio go-librespot[1086]: time="2024-08-23T15:11:28Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED"
Aug 23 15:11:28 volumio go-librespot[1086]: time="2024-08-23T15:11:28Z" level=trace msg="emitting websocket event: metadata"
Aug 23 15:11:28 volumio volumio[909]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:4LRxmh62YHxSHL4hGLq0ln","name":"Island","artist_names":["Miley Cyrus"],"album_name":"Endless Summer Vacation","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02cd222052a2594be29a6616b5","position":1,"duration":239573,"release_date":"year:2023 month:8 day:18","track_number":12,"disc_number":1}}
Aug 23 15:11:28 volumio go-librespot[1086]: time="2024-08-23T15:11:28Z" level=debug msg="fetched chunk 3/19, size: 524288"
Aug 23 15:11:28 volumio go-librespot[1086]: time="2024-08-23T15:11:28Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED"
Aug 23 15:11:28 volumio go-librespot[1086]: time="2024-08-23T15:11:28Z" level=trace msg="emitting websocket event: playing"
Aug 23 15:11:28 volumio volumio[909]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:4LRxmh62YHxSHL4hGLq0ln","play_origin":"playlist"}}
Aug 23 15:11:28 volumio volumio[909]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Aug 23 15:11:28 volumio volumio[909]: TypeError: Cannot read property 'service' of undefined
Aug 23 15:11:28 volumio volumio[909]: at ControllerSpotify.identifyPlaybackMode (/data/plugins/music_service/spop/index.js:336:50)
Aug 23 15:11:28 volumio volumio[909]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:262:18)
Aug 23 15:11:28 volumio volumio[909]: at WebSocket.message (/data/plugins/music_service/spop/index.js:189:14)
Aug 23 15:11:28 volumio volumio[909]: at WebSocket.emit (events.js:315:20)
Aug 23 15:11:28 volumio volumio[909]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1203:20)
Aug 23 15:11:28 volumio volumio[909]: at Receiver.emit (events.js:315:20)
Aug 23 15:11:28 volumio volumio[909]: at /data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:608:16
Aug 23 15:11:28 volumio volumio[909]: at internal/process/task_queues.js:149:7
Aug 23 15:11:28 volumio volumio[909]: at AsyncResource.runInAsyncScope (async_hooks.js:197:9)
Aug 23 15:11:28 volumio volumio[909]: at AsyncResource.runMicrotask (internal/process/task_queues.js:146:8)
Aug 23 15:11:28 volumio volumio[909]: at runMicrotasks ()
Aug 23 15:11:28 volumio volumio[909]: at processTicksAndRejections (internal/process/task_queues.js:93:5)
Aug 23 15:11:28 volumio volumio[909]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Aug 23 15:11:28 volumio go-librespot[1086]: time="2024-08-23T15:11:28Z" level=debug msg="fetched chunk 1/19, size: 524288"
Aug 23 15:11:29 volumio sudo[5491]: volumio : unable to resolve host volumio
Aug 23 15:11:29 volumio sudo[5491]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ;
Aug 23 15:11:29 volumio sudo[5491]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-23 15:10
Aug 23 15:11:29 volumio sudo[5491]: 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a"
VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6"
VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a"
VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST"
VOLUMIO_VERSION="3.742"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"