-- Logs begin at Sun 2024-02-04 06:45:03 UTC, end at Sun 2026-02-08 02:28:51 UTC. -- Feb 08 02:27:14 volumio-lwt volumio[1101]: error: error Feb 08 02:28:04 volumio-lwt volumio[1101]: error: error Feb 08 02:28:32 volumio-lwt volumio[1101]: info: CoreCommandRouter::servicePushState Feb 08 02:28:32 volumio-lwt volumio[1101]: info: CoreStateMachine::pushState Feb 08 02:28:32 volumio-lwt volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 08 02:28:32 volumio-lwt volumio[1101]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 02:28:49 volumio-lwt volumio[1236]: [Vollibrespot] : Connecting to AP "ap-gae2.spotify.com:443" Feb 08 02:28:49 volumio-lwt volumio[1236]: [Vollibrespot] : Authenticated as "8gqo98cvgoo0kt11hrnqvsidn" ! Feb 08 02:28:49 volumio-lwt volumio[1236]: [Vollibrespot] : Setting up new mixer: card:hw:5 mixer:PCM index:0 Feb 08 02:28:49 volumio-lwt volumio[1236]: [Vollibrespot] : Alsa min-db is not SND_CTL_TLV_DB_GAIN_MUTE!! Feb 08 02:28:49 volumio-lwt volumio[1236]: [Vollibrespot] : Alsa Mixer info min: 0 (MilliBel(-12799)[dB]) -- max: 32767 (MilliBel(0)[dB]) HW: true Feb 08 02:28:49 volumio-lwt volumio[1236]: [Vollibrespot] : Alsa min-db is not SND_CTL_TLV_DB_GAIN_MUTE!! Feb 08 02:28:49 volumio-lwt volumio[1236]: [Vollibrespot] : Using alsa sink Feb 08 02:28:49 volumio-lwt volumio[1236]: [Vollibrespot] : Metadata pipe established Feb 08 02:28:49 volumio-lwt volumio[1236]: [Vollibrespot] : Event: Volume { volume_to_mixer: 0 } Feb 08 02:28:49 volumio-lwt volumio[1236]: [Vollibrespot] : Country: "AU" Feb 08 02:28:49 volumio-lwt volumio[1101]: [SpotifyConnect] Volume Spotify: 0 Volumio: 0 Feb 08 02:28:50 volumio-lwt volumio[1236]: [Vollibrespot] : Fetching autoplay context uri Feb 08 02:28:50 volumio-lwt volumio[1236]: [Vollibrespot] : Event: SessionActive { became_active_at: 1770517730590 } Feb 08 02:28:50 volumio-lwt volumio[1236]: [Vollibrespot] : SessionActive! Feb 08 02:28:50 volumio-lwt volumio[1101]: [SpotifyConnect] A connect session has begun Feb 08 02:28:50 volumio-lwt volumio[1101]: info: Acquiring new spotify session Feb 08 02:28:50 volumio-lwt volumio[1101]: info: CoreCommandRouter::volumioStop Feb 08 02:28:50 volumio-lwt volumio[1101]: info: CoreStateMachine::stop Feb 08 02:28:50 volumio-lwt volumio[1101]: info: CoreStateMachine::serviceStop Feb 08 02:28:50 volumio-lwt volumio[1236]: [Vollibrespot] : Event: PlaybackStarted { track_id: SpotifyId { id: 75134164219316863275410633674995300100, audio_type: Track } } Feb 08 02:28:50 volumio-lwt volumio[1236]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 75134164219316863275410633674995300100, audio_type: Track } } Feb 08 02:28:50 volumio-lwt volumio[1101]: info: CoreCommandRouter::serviceStop Feb 08 02:28:50 volumio-lwt volumio[1101]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 08 02:28:50 volumio-lwt volumio[1101]: TypeError: Cannot read property 'then' of undefined Feb 08 02:28:50 volumio-lwt volumio[1101]: at SpotConnEvents.SpotConn.on (/data/plugins/music_service/spop/index.js:2095:31) Feb 08 02:28:50 volumio-lwt volumio[1101]: at emitNone (events.js:106:13) Feb 08 02:28:50 volumio-lwt volumio[1101]: at SpotConnEvents.emit (events.js:208:7) Feb 08 02:28:50 volumio-lwt volumio[1101]: at SpotConnEvents.parseData (/data/plugins/music_service/spop/SpotConnController.js:81:16) Feb 08 02:28:50 volumio-lwt volumio[1101]: at Socket.SpotConnEvents._udpsource.on.msg (/data/plugins/music_service/spop/SpotConnController.js:15:12) Feb 08 02:28:50 volumio-lwt volumio[1101]: at emitTwo (events.js:126:13) Feb 08 02:28:50 volumio-lwt volumio[1101]: at Socket.emit (events.js:214:7) Feb 08 02:28:50 volumio-lwt volumio[1101]: at UDP.onMessage [as onmessage] (dgram.js:659:8) Feb 08 02:28:50 volumio-lwt volumio[1101]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 08 02:28:50 volumio-lwt vtcs[1536]: [2026-02-08 02:28:50.598] [tisoc] [warning] [PlaybackControllerImpl.cpp:472] Illegal state:0 Feb 08 02:28:50 volumio-lwt vtcs[1536]: [2026-02-08 02:28:50] [info] asio async_shutdown error: asio.misc:2 (End of file) Feb 08 02:28:50 volumio-lwt volumio[1236]: [Vollibrespot] : Autoplay uri resolved to <"spotify:station:playlist:7pcwl7pxlG14JJGv0KzxAp"> Feb 08 02:28:50 volumio-lwt volumio[1236]: [Vollibrespot] : Resolving uri "spotify:station:playlist:7pcwl7pxlG14JJGv0KzxAp" Feb 08 02:28:50 volumio-lwt volumio[1236]: [Vollibrespot] : Event: GotToken { token: Token { access_token: "BQAEisdJS_If1OBXE1DIsKL9sFmUAL3AGXQSP_88rhrFydEMZ6ew8SonoyJwrfq7IjPIzk6nOkO4hqO1DI9hBWQDusAqXWMdoVg8h5YfL2vtUB9A51tWa5MgDNFG0PV3uamYaL0tNLs1rEw7G3F_CJqztbqNxe6wSG7Sw0eMRgcH0owA-63s4QBBxl64nvbxA4ElkexKTzjPy3g90qWsABIjwHYr96zUZE2Cb3_SUNqeRtMfnwtb2xPIQFmsmbLMfJrxVA", expires_in: 3600, token_type: "Bearer", scope: ["streaming", "user-modify-playback-state", "user-read-playback-state", "user-read-currently-playing", "user-read-private"] } } Feb 08 02:28:50 volumio-lwt volumio[1236]: [Vollibrespot] : Loading with Spotify URI Feb 08 02:28:51 volumio-lwt sudo[15939]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-08 02:27 Feb 08 02:28:51 volumio-lwt sudo[15939]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" 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="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="958dedc0edff114f244fa9acc70cf53089d9e5ae" VOLUMIO_BE_VERSION="fd3e37ffec12db034ec93f94dbc26e5495d5b91a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Oct 6 12:34:49 CEST 2021" VOLUMIO_VERSION="2.917" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="98c8f08c531a6f0456f17ab17795e35f"