-- Logs begin at Mon 2025-03-17 20:40:50 CET, end at Sat 2025-04-19 20:30:11 CEST. -- Apr 19 20:30:11 volumio systemd[1]: Starting Daily apt download activities... Apr 19 20:30:11 volumio go-librespot[1386]: time="2025-04-19T20:30:11+02:00" level=trace msg="scheduling prefetch in 201s" Apr 19 20:30:11 volumio go-librespot[1386]: time="2025-04-19T20:30:11+02:00" level=trace msg="emitting websocket event: metadata" Apr 19 20:30:11 volumio go-librespot[1386]: time="2025-04-19T20:30:11+02:00" level=trace msg="emitting websocket event: active" Apr 19 20:30:11 volumio go-librespot[1386]: time="2025-04-19T20:30:11+02:00" level=debug msg="sending successful reply for dealer request" Apr 19 20:30:11 volumio volumio[843]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:5YTtTbqxUzJHD3CpbNHerP","name":"Brighter Than The Sun","artist_names":["Colbie Caillat"],"album_name":"All Of You","album_cover_url":"https://i.scdn.co/image/ab67616d00001e023baae0836a5b6ee9d649ed14","position":-2846939068,"duration":231213,"release_date":"year:2011 month:1 day:1","track_number":1,"disc_number":1}} Apr 19 20:30:11 volumio go-librespot[1386]: time="2025-04-19T20:30:11+02:00" level=debug msg="fetched chunk 3/20, size: 524288" uri="spotify:track:5YTtTbqxUzJHD3CpbNHerP" Apr 19 20:30:11 volumio volumio[843]: SPOTIFY: received: {"type":"active","data":null} Apr 19 20:30:11 volumio systemd[1]: Starting Daily man-db regeneration... Apr 19 20:30:11 volumio volumio[843]: info: Aligning Spotify Volume to Volumio Volume Apr 19 20:30:11 volumio volumio[843]: info: CoreCommandRouter::volumioGetState Apr 19 20:30:11 volumio volumio[843]: info: CorePlayQueue::getTrack 0 Apr 19 20:30:11 volumio volumio[843]: info: Setting Spotify Volume from Volumio: 20 Apr 19 20:30:11 volumio systemd-timedated[1479]: Changed local time to Sat Apr 19 20:30:11 2025 Apr 19 20:30:11 volumio sudo[1472]: pam_unix(sudo:session): session closed for user root Apr 19 20:30:11 volumio volumio[843]: info: Discovery: A device disappeared from network Apr 19 20:30:11 volumio volumio[843]: info: Discovery: Device volumio disappeared from network Apr 19 20:30:11 volumio volumio-time-update[540]: volumio-time-update-util: System time updated successfully. Apr 19 20:30:11 volumio systemd[1]: Started Volumio Time Update Utility. Apr 19 20:30:11 volumio systemd[1]: Reached target Multi-User System. Apr 19 20:30:11 volumio systemd[1]: Reached target Graphical Interface. Apr 19 20:30:11 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Apr 19 20:30:11 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Apr 19 20:30:11 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Apr 19 20:30:11 volumio go-librespot[1386]: time="2025-04-19T20:30:11+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 19 20:30:11 volumio go-librespot[1386]: time="2025-04-19T20:30:11+02:00" level=trace msg="emitting websocket event: playing" Apr 19 20:30:11 volumio volumio[843]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:5YTtTbqxUzJHD3CpbNHerP","play_origin":"playlist/ondemand"}} Apr 19 20:30:11 volumio volumio[843]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 19 20:30:11 volumio volumio[843]: TypeError: Cannot read property 'service' of undefined Apr 19 20:30:11 volumio volumio[843]: at ControllerSpotify.identifyPlaybackMode (/data/plugins/music_service/spop/index.js:337:50) Apr 19 20:30:11 volumio volumio[843]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:263:18) Apr 19 20:30:11 volumio volumio[843]: at WebSocket.message (/data/plugins/music_service/spop/index.js:190:14) Apr 19 20:30:11 volumio volumio[843]: at WebSocket.emit (events.js:400:28) Apr 19 20:30:11 volumio volumio[843]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1220:20) Apr 19 20:30:11 volumio volumio[843]: at Receiver.emit (events.js:400:28) Apr 19 20:30:11 volumio volumio[843]: at Receiver.dataMessage (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:596:14) Apr 19 20:30:11 volumio volumio[843]: at Receiver.getData (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:496:10) Apr 19 20:30:11 volumio volumio[843]: at Receiver.startLoop (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:167:16) Apr 19 20:30:11 volumio volumio[843]: at Receiver._write (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:94:10) Apr 19 20:30:11 volumio volumio[843]: at writeOrBuffer (internal/streams/writable.js:358:12) Apr 19 20:30:11 volumio volumio[843]: at Receiver.Writable.write (internal/streams/writable.js:303:10) Apr 19 20:30:11 volumio volumio[843]: at Socket.socketOnData (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1355:35) Apr 19 20:30:11 volumio volumio[843]: at Socket.emit (events.js:400:28) Apr 19 20:30:11 volumio volumio[843]: at addChunk (internal/streams/readable.js:293:12) Apr 19 20:30:11 volumio volumio[843]: at readableAddChunk (internal/streams/readable.js:267:9) Apr 19 20:30:11 volumio volumio[843]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 19 20:30:11 volumio go-librespot[1386]: time="2025-04-19T20:30:11+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Apr 19 20:30:11 volumio go-librespot[1386]: time="2025-04-19T20:30:11+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 345" Apr 19 20:30:11 volumio systemd[1]: man-db.service: Succeeded. Apr 19 20:30:11 volumio systemd[1]: Started Daily man-db regeneration. Apr 19 20:30:11 volumio go-librespot[1386]: time="2025-04-19T20:30:11+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Apr 19 20:30:11 volumio go-librespot[1386]: time="2025-04-19T20:30:11+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1328" Apr 19 20:30:11 volumio sudo[1528]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-19 20:29 Apr 19 20:30:11 volumio sudo[1528]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 20:30:11 volumio go-librespot[1386]: time="2025-04-19T20:30:11+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Apr 19 20:30:11 volumio go-librespot[1386]: time="2025-04-19T20:30:11+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1108" PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="a01a833dd28483f88cb390596f5090b7187992af" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 27 Feb 2025 12:02:39 PM CET" VOLUMIO_VERSION="3.795" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_DEVICENAME="Asus Tinkerboard" VOLUMIO_HASH="e62831d2665580a7904f9309b43b2eea"