-- Logs begin at Fri 2024-08-09 15:43:08 +06, end at Wed 2024-09-04 19:25:58 +06. -- Sep 04 19:25:52 russells-pi-4 systemd[1]: Starting Daily apt download activities... Sep 04 19:25:52 russells-pi-4 volumio[1176]: ........................................................................++++ Sep 04 19:25:52 russells-pi-4 go-librespot[1235]: time="2024-09-04T19:25:52+06:00" level=debug msg="fetched chunk 5/29, size: 524288" Sep 04 19:25:52 russells-pi-4 go-librespot[1235]: time="2024-09-04T19:25:52+06:00" level=debug msg="fetched chunk 6/29, size: 524288" Sep 04 19:25:52 russells-pi-4 go-librespot[1235]: time="2024-09-04T19:25:52+06:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Sep 04 19:25:52 russells-pi-4 go-librespot[1235]: time="2024-09-04T19:25:52+06:00" level=trace msg="emitting websocket event: metadata" Sep 04 19:25:52 russells-pi-4 go-librespot[1235]: time="2024-09-04T19:25:52+06:00" level=trace msg="emitting websocket event: active" Sep 04 19:25:52 russells-pi-4 go-librespot[1235]: time="2024-09-04T19:25:52+06:00" level=debug msg="sending successful reply for delaer request" Sep 04 19:25:52 russells-pi-4 go-librespot[1235]: time="2024-09-04T19:25:52+06:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Sep 04 19:25:52 russells-pi-4 go-librespot[1235]: time="2024-09-04T19:25:52+06:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Sep 04 19:25:52 russells-pi-4 go-librespot[1235]: time="2024-09-04T19:25:52+06:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Sep 04 19:25:52 russells-pi-4 go-librespot[1235]: time="2024-09-04T19:25:52+06:00" level=debug msg="fetched chunk 7/29, size: 524288" Sep 04 19:25:52 russells-pi-4 go-librespot[1235]: time="2024-09-04T19:25:52+06:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Sep 04 19:25:52 russells-pi-4 go-librespot[1235]: time="2024-09-04T19:25:52+06:00" level=trace msg="emitting websocket event: paused" Sep 04 19:25:52 russells-pi-4 go-librespot[1235]: time="2024-09-04T19:25:52+06:00" level=debug msg="handling resume player command from 9a6b8223c52a45b7eb93ef3d3a9c6765af819b2e" Sep 04 19:25:52 russells-pi-4 go-librespot[1235]: time="2024-09-04T19:25:52+06:00" level=trace msg="seek to 81838ms (diff: 161ms, samples: 3609055, bytes: 2412426)" Sep 04 19:25:52 russells-pi-4 go-librespot[1235]: time="2024-09-04T19:25:52+06:00" level=debug msg="resume track at 81677ms" Sep 04 19:25:52 russells-pi-4 volumio[989]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:628MEMZkSeeM9IjPshuBA5","name":"The Answer Is Yes","artist_names":["Jim Hall"],"album_name":"Concierto","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02e5ab01298a9138745f0eba47","position":81838,"duration":456600,"release_date":"year:1975","track_number":3,"disc_number":1}} Sep 04 19:25:52 russells-pi-4 volumio[989]: SPOTIFY: received: {"type":"active","data":null} Sep 04 19:25:52 russells-pi-4 volumio[989]: info: Aligning Spotify Volume to Volumio Volume Sep 04 19:25:52 russells-pi-4 volumio[989]: info: CoreCommandRouter::volumioGetState Sep 04 19:25:52 russells-pi-4 volumio[989]: info: CorePlayQueue::getTrack 0 Sep 04 19:25:52 russells-pi-4 volumio[989]: info: Setting Spotify Volume from Volumio: 41 Sep 04 19:25:52 russells-pi-4 volumio[989]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:628MEMZkSeeM9IjPshuBA5","play_origin":"album"}} Sep 04 19:25:52 russells-pi-4 volumio[989]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 04 19:25:52 russells-pi-4 volumio[989]: TypeError: Cannot read property 'service' of undefined Sep 04 19:25:52 russells-pi-4 volumio[989]: at ControllerSpotify.identifyPlaybackMode (/data/plugins/music_service/spop/index.js:336:50) Sep 04 19:25:52 russells-pi-4 volumio[989]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:270:18) Sep 04 19:25:52 russells-pi-4 volumio[989]: at WebSocket.message (/data/plugins/music_service/spop/index.js:189:14) Sep 04 19:25:52 russells-pi-4 volumio[989]: at WebSocket.emit (events.js:315:20) Sep 04 19:25:52 russells-pi-4 volumio[989]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1203:20) Sep 04 19:25:52 russells-pi-4 volumio[989]: at Receiver.emit (events.js:315:20) Sep 04 19:25:52 russells-pi-4 volumio[989]: at /data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:608:16 Sep 04 19:25:52 russells-pi-4 volumio[989]: at internal/process/task_queues.js:149:7 Sep 04 19:25:52 russells-pi-4 volumio[989]: at AsyncResource.runInAsyncScope (async_hooks.js:197:9) Sep 04 19:25:52 russells-pi-4 volumio[989]: at AsyncResource.runMicrotask (internal/process/task_queues.js:146:8) Sep 04 19:25:52 russells-pi-4 volumio[989]: at runMicrotasks () Sep 04 19:25:52 russells-pi-4 volumio[989]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Sep 04 19:25:52 russells-pi-4 volumio[989]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 04 19:25:52 russells-pi-4 go-librespot[1235]: time="2024-09-04T19:25:52+06:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Sep 04 19:25:52 russells-pi-4 go-librespot[1235]: time="2024-09-04T19:25:52+06:00" level=debug msg="sending successful reply for delaer request" Sep 04 19:25:53 russells-pi-4 systemd[1]: apt-daily.service: Succeeded. Sep 04 19:25:53 russells-pi-4 systemd[1]: Started Daily apt download activities. Sep 04 19:25:53 russells-pi-4 go-librespot[1235]: time="2024-09-04T19:25:53+06:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Sep 04 19:25:53 russells-pi-4 go-librespot[1235]: time="2024-09-04T19:25:53+06:00" level=trace msg="emitting websocket event: playing" Sep 04 19:25:53 russells-pi-4 systemd[1]: Starting Daily apt upgrade and clean activities... Sep 04 19:25:53 russells-pi-4 systemd[1]: apt-daily-upgrade.service: Succeeded. Sep 04 19:25:53 russells-pi-4 systemd[1]: Started Daily apt upgrade and clean activities. Sep 04 19:25:58 russells-pi-4 go-librespot[1235]: time="2024-09-04T19:25:58+06:00" level=debug msg="fetched chunk 8/29, size: 524288" Sep 04 19:25:58 russells-pi-4 sudo[1335]: volumio : unable to resolve host russells-pi-4 Sep 04 19:25:58 russells-pi-4 sudo[1335]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 04 19:25:58 russells-pi-4 sudo[1335]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-04 19:24 Sep 04 19:25:58 russells-pi-4 sudo[1335]: 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"