-- Logs begin at Sun 2025-01-12 13:58:36 GMT, end at Tue 2025-03-04 17:58:04 GMT. -- Mar 04 17:57:00 volumio-shiny kernel: hwmon hwmon1: Undervoltage detected! Mar 04 17:57:07 volumio-shiny kernel: hwmon hwmon1: Voltage normalised Mar 04 17:57:11 volumio-shiny kernel: hwmon hwmon1: Undervoltage detected! Mar 04 17:57:17 volumio-shiny kernel: hwmon hwmon1: Voltage normalised Mar 04 17:57:21 volumio-shiny kernel: hwmon hwmon1: Undervoltage detected! Mar 04 17:57:25 volumio-shiny kernel: hwmon hwmon1: Voltage normalised Mar 04 17:57:31 volumio-shiny kernel: hwmon hwmon1: Undervoltage detected! Mar 04 17:57:36 volumio-shiny kernel: hwmon hwmon1: Voltage normalised Mar 04 17:57:42 volumio-shiny kernel: hwmon hwmon1: Undervoltage detected! Mar 04 17:57:46 volumio-shiny kernel: hwmon hwmon1: Voltage normalised Mar 04 17:57:50 volumio-shiny kernel: hwmon hwmon1: Undervoltage detected! Mar 04 17:57:56 volumio-shiny kernel: hwmon hwmon1: Voltage normalised Mar 04 17:58:01 volumio-shiny kernel: hwmon hwmon1: Undervoltage detected! Mar 04 17:58:02 volumio-shiny volumio[1733]: [Vollibrespot] : Connecting to AP "ap-gew1.spotify.com:443" Mar 04 17:58:02 volumio-shiny volumio[1733]: [Vollibrespot] : Authenticated as "lnyttfsg25bv6zpm4q02wl03w" ! Mar 04 17:58:02 volumio-shiny volumio[1733]: [Vollibrespot] : Using alsa sink Mar 04 17:58:02 volumio-shiny volumio[1733]: [Vollibrespot] : Metadata pipe established Mar 04 17:58:02 volumio-shiny volumio[1733]: [Vollibrespot] : Country: "GB" Mar 04 17:58:02 volumio-shiny volumio[1733]: [Vollibrespot] : Event: Volume { volume_to_mixer: 21626 } Mar 04 17:58:02 volumio-shiny volumio[966]: [SpotifyConnect] 32.99916075379568 Mar 04 17:58:02 volumio-shiny volumio[966]: [SpotifyConnect] Volume: Spotify:32.99916075379568 Volumio: 33 Mar 04 17:58:02 volumio-shiny volumio[966]: info: CoreStateMachine::pushState Mar 04 17:58:02 volumio-shiny volumio[966]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 17:58:02 volumio-shiny volumio[966]: info: CoreCommandRouter::volumioPushState Mar 04 17:58:02 volumio-shiny volumio[966]: info: MRS: Pushing multiroomSync output update for this device Mar 04 17:58:02 volumio-shiny volumio[966]: info: MRS: Pushing multiroomSync output Mar 04 17:58:03 volumio-shiny volumio[1733]: [Vollibrespot] : Event: SessionActive { became_active_at: 1741111083111 } Mar 04 17:58:03 volumio-shiny volumio[1733]: [Vollibrespot] : SessionActive! Mar 04 17:58:03 volumio-shiny volumio[1733]: [Vollibrespot] : Fetching autoplay context uri Mar 04 17:58:03 volumio-shiny volumio[1733]: [Vollibrespot] : Event: PlaybackStarted { track_id: SpotifyId { id: 260481389737555759314118956550078706844, audio_type: Track } } Mar 04 17:58:03 volumio-shiny volumio[1733]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 260481389737555759314118956550078706844, audio_type: Track } } Mar 04 17:58:03 volumio-shiny volumio[966]: [SpotifyConnect] A connect session has begun Mar 04 17:58:03 volumio-shiny volumio[966]: [SpotifyConnect] Device palyback is active! Mar 04 17:58:03 volumio-shiny volumio[966]: info: CoreCommandRouter::volumioGetState Mar 04 17:58:03 volumio-shiny volumio[966]: [SpotifyConnect] Currently active: tidalconnect Mar 04 17:58:03 volumio-shiny volumio[966]: [SpotifyConnect] Stopping currently active service Mar 04 17:58:03 volumio-shiny volumio[966]: info: CoreCommandRouter::volumioStop Mar 04 17:58:03 volumio-shiny volumio[966]: info: CoreStateMachine::stop Mar 04 17:58:03 volumio-shiny volumio[966]: info: CoreStateMachine::serviceStop Mar 04 17:58:03 volumio-shiny volumio[966]: info: CoreCommandRouter::serviceStop Mar 04 17:58:03 volumio-shiny volumio[1733]: [Vollibrespot] : Loading with Spotify URI Mar 04 17:58:03 volumio-shiny volumio[966]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 04 17:58:03 volumio-shiny volumio[1733]: [Vollibrespot] : Autoplay uri resolved to <"spotify:station:playlist:5qUkthigPPOiAkAFD5sV30"> Mar 04 17:58:03 volumio-shiny volumio[1733]: [Vollibrespot] : Resolving uri "spotify:station:playlist:5qUkthigPPOiAkAFD5sV30" Mar 04 17:58:03 volumio-shiny volumio[1733]: [Vollibrespot] : Event: GotToken { token: Token { access_token: "BQCHaW7FSjzSldA483-bkAM9e14jR4oiqrj_mXqqdTNbzy1htpLfQTTNb-1WPnHGIfMzuAz1p4wf73VYzAlOZGIUGbEOAfESgzxonFncH_9kuPPjCfgkidZ7YtwdjcONg8q4HVnrpSPdRlFECEjwQPR_QF3p_BkGi-05WB0ZngjxRj1t9TE7rrDT3rmGjvPoVelbiwVAf0s493BP140CeVNiGGlNVCfxNml9sjS9vUCFColezRPl7Gvr3A", expires_in: 3600, token_type: "Bearer", scope: ["streaming", "user-read-playback-state", "user-modify-playback-state", "user-read-currently-playing", "user-read-private"] } } Mar 04 17:58:03 volumio-shiny volumio[966]: TypeError: Cannot read property 'then' of undefined Mar 04 17:58:03 volumio-shiny volumio[966]: at SpotConnEvents. (/data/plugins/music_service/volspotconnect2/index.js:143:23) Mar 04 17:58:03 volumio-shiny volumio[966]: at SpotConnEvents.emit (events.js:315:20) Mar 04 17:58:03 volumio-shiny volumio[966]: at SpotConnEvents.parseData (/data/plugins/music_service/volspotconnect2/SpotConnController.js:73:16) Mar 04 17:58:03 volumio-shiny volumio[966]: at Socket. (/data/plugins/music_service/volspotconnect2/SpotConnController.js:15:12) Mar 04 17:58:03 volumio-shiny volumio[966]: at Socket.emit (events.js:315:20) Mar 04 17:58:03 volumio-shiny volumio[966]: at UDP.onMessage [as onmessage] (dgram.js:919:8) Mar 04 17:58:03 volumio-shiny volumio[966]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 04 17:58:03 volumio-shiny vtcs[1783]: [2025-03-04 17:58:03.475] [tisoc] [warning] [PlaybackControllerImpl.cpp:510] Illegal state:0 Mar 04 17:58:03 volumio-shiny volumio[1733]: [Vollibrespot] : (191000 ms) loaded Mar 04 17:58:03 volumio-shiny volumio[1733]: [Vollibrespot] : Resolved 50 tracks from <"spotify:user:lnyttfsg25bv6zpm4q02wl03w:playlist:5qUkthigPPOiAkAFD5sV30"> Mar 04 17:58:03 volumio-shiny volumio[1733]: [Vollibrespot] : Event: SinkActive Mar 04 17:58:04 volumio-shiny sudo[17129]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-04 17:57 Mar 04 17:58:04 volumio-shiny sudo[17129]: 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="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"