-- Logs begin at Sat 2025-04-19 00:39:43 BST, end at Mon 2025-04-21 12:48:05 BST. -- Apr 21 12:47:02 volumio-shiny kernel: hwmon hwmon1: Undervoltage detected! Apr 21 12:47:06 volumio-shiny kernel: hwmon hwmon1: Voltage normalised Apr 21 12:47:12 volumio-shiny kernel: hwmon hwmon1: Undervoltage detected! Apr 21 12:47:16 volumio-shiny kernel: hwmon hwmon1: Voltage normalised Apr 21 12:47:23 volumio-shiny kernel: hwmon hwmon1: Undervoltage detected! Apr 21 12:47:27 volumio-shiny kernel: hwmon hwmon1: Voltage normalised Apr 21 12:47:31 volumio-shiny kernel: hwmon hwmon1: Undervoltage detected! Apr 21 12:47:37 volumio-shiny kernel: hwmon hwmon1: Voltage normalised Apr 21 12:47:41 volumio-shiny kernel: hwmon hwmon1: Undervoltage detected! Apr 21 12:47:48 volumio-shiny kernel: hwmon hwmon1: Voltage normalised Apr 21 12:47:52 volumio-shiny kernel: hwmon hwmon1: Undervoltage detected! Apr 21 12:47:58 volumio-shiny kernel: hwmon hwmon1: Voltage normalised Apr 21 12:47:58 volumio-shiny systemd[1]: Starting Cleanup of Temporary Directories... Apr 21 12:47:58 volumio-shiny volumio[976]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 21 12:47:58 volumio-shiny systemd[1]: systemd-tmpfiles-clean.service: Succeeded. Apr 21 12:47:58 volumio-shiny systemd[1]: Started Cleanup of Temporary Directories. Apr 21 12:48:02 volumio-shiny kernel: hwmon hwmon1: Undervoltage detected! Apr 21 12:48:02 volumio-shiny volumio[22193]: [Vollibrespot] : Connecting to AP "ap-gew1.spotify.com:443" Apr 21 12:48:03 volumio-shiny volumio[22193]: [Vollibrespot] : Authenticated as "a36e9qdeev8ub5w3rrgolrqwm" ! Apr 21 12:48:03 volumio-shiny volumio[22193]: [Vollibrespot] : Using alsa sink Apr 21 12:48:03 volumio-shiny volumio[22193]: [Vollibrespot] : Metadata pipe established Apr 21 12:48:03 volumio-shiny volumio[22193]: [Vollibrespot] : Country: "GB" Apr 21 12:48:03 volumio-shiny volumio[22193]: [Vollibrespot] : Event: Volume { volume_to_mixer: 21626 } Apr 21 12:48:03 volumio-shiny volumio[976]: [SpotifyConnect] 32.99916075379568 Apr 21 12:48:03 volumio-shiny volumio[976]: [SpotifyConnect] Volume: Spotify:32.99916075379568 Volumio: 33 Apr 21 12:48:03 volumio-shiny volumio[976]: info: CoreStateMachine::pushState Apr 21 12:48:03 volumio-shiny volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 21 12:48:03 volumio-shiny volumio[976]: info: CoreCommandRouter::volumioPushState Apr 21 12:48:03 volumio-shiny volumio[976]: info: MRS: Pushing multiroomSync output update for this device Apr 21 12:48:03 volumio-shiny volumio[976]: info: MRS: Pushing multiroomSync output Apr 21 12:48:03 volumio-shiny volumio[22193]: [Vollibrespot] : Event: SessionActive { became_active_at: 1745236083938 } Apr 21 12:48:03 volumio-shiny volumio[22193]: [Vollibrespot] : SessionActive! Apr 21 12:48:03 volumio-shiny volumio[22193]: [Vollibrespot] : Fetching autoplay context uri Apr 21 12:48:03 volumio-shiny volumio[22193]: [Vollibrespot] : Event: PlaybackStarted { track_id: SpotifyId { id: 273397013819115223317049341658626761616, audio_type: Track } } Apr 21 12:48:03 volumio-shiny volumio[22193]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 273397013819115223317049341658626761616, audio_type: Track } } Apr 21 12:48:03 volumio-shiny volumio[976]: [SpotifyConnect] A connect session has begun Apr 21 12:48:03 volumio-shiny volumio[976]: [SpotifyConnect] Device palyback is active! Apr 21 12:48:03 volumio-shiny volumio[976]: info: CoreCommandRouter::volumioGetState Apr 21 12:48:03 volumio-shiny volumio[976]: [SpotifyConnect] Currently active: tidalconnect Apr 21 12:48:03 volumio-shiny volumio[976]: [SpotifyConnect] Stopping currently active service Apr 21 12:48:03 volumio-shiny volumio[976]: info: CoreCommandRouter::volumioStop Apr 21 12:48:03 volumio-shiny volumio[976]: info: CoreStateMachine::stop Apr 21 12:48:03 volumio-shiny volumio[976]: info: CoreStateMachine::serviceStop Apr 21 12:48:03 volumio-shiny volumio[976]: info: CoreCommandRouter::serviceStop Apr 21 12:48:03 volumio-shiny volumio[22193]: [Vollibrespot] : Loading with Spotify URI Apr 21 12:48:03 volumio-shiny volumio[976]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 21 12:48:03 volumio-shiny volumio[22193]: [Vollibrespot] : Autoplay uri resolved to <"spotify:station:playlist:37i9dQZF1E36VRyFVLzLi5"> Apr 21 12:48:03 volumio-shiny volumio[22193]: [Vollibrespot] : Resolving uri "spotify:station:playlist:37i9dQZF1E36VRyFVLzLi5" Apr 21 12:48:03 volumio-shiny volumio[22193]: [Vollibrespot] : Event: GotToken { token: Token { access_token: "BQBsgepGwPJV3gzTyMGloZZZmY9GeAscmjjVurpNQq6D6iEjVUiq_eu4RV9iwG3zgPYYRCe115OuDkP-QYn-ttZl9IeJJGupPhcPDKKPwZ2bQtFwz53iOrVZXqOt6lRQPCTBbUVZHuTy8gfTJd_BQJRUR_RVkG3wr0hoaBOhnY1bxn2U6GOUgdVH0A7aUru_XVWivqixZqhOxWNbh8sxYEpVyvFryV5Pa0utmJzWvfDcyA7Hxev7zv4uJcRiLQ", expires_in: 3600, token_type: "Bearer", scope: ["streaming", "user-read-playback-state", "user-modify-playback-state", "user-read-currently-playing", "user-read-private"] } } Apr 21 12:48:03 volumio-shiny volumio[976]: TypeError: Cannot read property 'then' of undefined Apr 21 12:48:03 volumio-shiny volumio[976]: at SpotConnEvents. (/data/plugins/music_service/volspotconnect2/index.js:143:23) Apr 21 12:48:03 volumio-shiny volumio[976]: at SpotConnEvents.emit (events.js:315:20) Apr 21 12:48:03 volumio-shiny volumio[976]: at SpotConnEvents.parseData (/data/plugins/music_service/volspotconnect2/SpotConnController.js:73:16) Apr 21 12:48:03 volumio-shiny volumio[976]: at Socket. (/data/plugins/music_service/volspotconnect2/SpotConnController.js:15:12) Apr 21 12:48:03 volumio-shiny volumio[976]: at Socket.emit (events.js:315:20) Apr 21 12:48:03 volumio-shiny volumio[976]: at UDP.onMessage [as onmessage] (dgram.js:919:8) Apr 21 12:48:03 volumio-shiny volumio[976]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 21 12:48:04 volumio-shiny volumio[22193]: [Vollibrespot] : (512919 ms) loaded Apr 21 12:48:04 volumio-shiny volumio[22193]: [Vollibrespot] : Event: PlaybackStopped { track_id: SpotifyId { id: 273397013819115223317049341658626761616, audio_type: Track } } Apr 21 12:48:04 volumio-shiny volumio[22193]: [Vollibrespot] : Resolved 50 tracks from <"spotify:user:a36e9qdeev8ub5w3rrgolrqwm:collection"> Apr 21 12:48:05 volumio-shiny sudo[3047]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-21 12:47 Apr 21 12:48:05 volumio-shiny sudo[3047]: 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"