-- Logs begin at Mon 2025-01-20 20:18:20 UTC, end at Sat 2025-12-13 12:41:20 UTC. -- Dec 13 12:40:03 wizard-iv-fn wpa_supplicant[743]: wlan0: Failed to initiate sched scan Dec 13 12:40:08 wizard-iv-fn wpa_supplicant[743]: wlan0: Failed to initiate sched scan Dec 13 12:40:14 wizard-iv-fn wpa_supplicant[743]: wlan0: Failed to initiate sched scan Dec 13 12:40:20 wizard-iv-fn wpa_supplicant[743]: wlan0: Failed to initiate sched scan Dec 13 12:40:25 wizard-iv-fn wpa_supplicant[743]: wlan0: Failed to initiate sched scan Dec 13 12:40:31 wizard-iv-fn wpa_supplicant[743]: wlan0: Failed to initiate sched scan Dec 13 12:40:36 wizard-iv-fn wpa_supplicant[743]: wlan0: Failed to initiate sched scan Dec 13 12:40:42 wizard-iv-fn wpa_supplicant[743]: wlan0: Failed to initiate sched scan Dec 13 12:40:48 wizard-iv-fn wpa_supplicant[743]: wlan0: Failed to initiate sched scan Dec 13 12:40:53 wizard-iv-fn wpa_supplicant[743]: wlan0: Failed to initiate sched scan Dec 13 12:40:59 wizard-iv-fn wpa_supplicant[743]: wlan0: Failed to initiate sched scan Dec 13 12:41:04 wizard-iv-fn wpa_supplicant[743]: wlan0: Failed to initiate sched scan Dec 13 12:41:10 wizard-iv-fn wpa_supplicant[743]: wlan0: Failed to initiate sched scan Dec 13 12:41:16 wizard-iv-fn wpa_supplicant[743]: wlan0: Failed to initiate sched scan Dec 13 12:41:18 wizard-iv-fn volumio[7240]: [Vollibrespot] : Connecting to AP "ap-gew4.spotify.com:443" Dec 13 12:41:18 wizard-iv-fn volumio[7240]: [Vollibrespot] : Authenticated as "frits.neuteboom" ! Dec 13 12:41:18 wizard-iv-fn volumio[7240]: [Vollibrespot] : Setting up new mixer: card:hw:2 mixer:Digital index:0 Dec 13 12:41:18 wizard-iv-fn volumio[7240]: [Vollibrespot] : Alsa min-db is not SND_CTL_TLV_DB_GAIN_MUTE!! Dec 13 12:41:18 wizard-iv-fn volumio[7240]: [Vollibrespot] : Alsa Mixer info min: 0 (MilliBel(-10000)[dB]) -- max: 100 (MilliBel(0)[dB]) HW: true Dec 13 12:41:18 wizard-iv-fn volumio[7240]: [Vollibrespot] : Alsa min-db is not SND_CTL_TLV_DB_GAIN_MUTE!! Dec 13 12:41:18 wizard-iv-fn volumio[7240]: [Vollibrespot] : Using alsa sink Dec 13 12:41:18 wizard-iv-fn volumio[7240]: [Vollibrespot] : Metadata pipe established Dec 13 12:41:18 wizard-iv-fn volumio[7240]: [Vollibrespot] : Country: "NL" Dec 13 12:41:18 wizard-iv-fn volumio[7240]: [Vollibrespot] : Event: Volume { volume_to_mixer: 0 } Dec 13 12:41:18 wizard-iv-fn volumio[936]: [SpotifyConnect] 0 Dec 13 12:41:18 wizard-iv-fn volumio[936]: [SpotifyConnect] Volume: Spotify:0 Volumio: 0 Dec 13 12:41:18 wizard-iv-fn volumio[936]: info: CoreStateMachine::pushState Dec 13 12:41:18 wizard-iv-fn volumio[936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 13 12:41:18 wizard-iv-fn volumio[936]: info: CoreCommandRouter::volumioPushState Dec 13 12:41:18 wizard-iv-fn volumio[7240]: [Vollibrespot] : Fetching autoplay context uri Dec 13 12:41:18 wizard-iv-fn volumio[7240]: [Vollibrespot] : Event: SessionActive { became_active_at: 1765629678939 } Dec 13 12:41:18 wizard-iv-fn volumio[7240]: [Vollibrespot] : SessionActive! Dec 13 12:41:18 wizard-iv-fn volumio[7240]: [Vollibrespot] : Event: PlaybackStarted { track_id: SpotifyId { id: 6325475104202640983083521896592962587, audio_type: Track } } Dec 13 12:41:18 wizard-iv-fn volumio[7240]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 6325475104202640983083521896592962587, audio_type: Track } } Dec 13 12:41:18 wizard-iv-fn volumio[936]: [SpotifyConnect] A connect session has begun Dec 13 12:41:18 wizard-iv-fn volumio[936]: [SpotifyConnect] Device palyback is active! Dec 13 12:41:18 wizard-iv-fn volumio[936]: info: CoreCommandRouter::volumioGetState Dec 13 12:41:18 wizard-iv-fn volumio[936]: [SpotifyConnect] Currently active: tidalconnect Dec 13 12:41:18 wizard-iv-fn volumio[936]: [SpotifyConnect] Stopping currently active service Dec 13 12:41:18 wizard-iv-fn volumio[936]: info: CoreCommandRouter::volumioStop Dec 13 12:41:18 wizard-iv-fn volumio[936]: info: CoreStateMachine::stop Dec 13 12:41:18 wizard-iv-fn volumio[936]: info: CoreStateMachine::serviceStop Dec 13 12:41:18 wizard-iv-fn volumio[936]: info: CoreCommandRouter::serviceStop Dec 13 12:41:18 wizard-iv-fn volumio[936]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 13 12:41:18 wizard-iv-fn volumio[7240]: [Vollibrespot] : Autoplay uri resolved to <"spotify:station:playlist:2FSdLEabZJhi25blbHIGgc"> Dec 13 12:41:18 wizard-iv-fn volumio[7240]: [Vollibrespot] : Resolving uri "spotify:station:playlist:2FSdLEabZJhi25blbHIGgc" Dec 13 12:41:18 wizard-iv-fn vtcs[1811]: [2025-12-13 12:41:18] [info] asio async_shutdown error: asio.misc:2 (End of file) Dec 13 12:41:18 wizard-iv-fn volumio[7240]: [Vollibrespot] : Loading with Spotify URI Dec 13 12:41:18 wizard-iv-fn volumio[936]: TypeError: Cannot read property 'then' of undefined Dec 13 12:41:18 wizard-iv-fn volumio[936]: at SpotConnEvents. (/data/plugins/music_service/volspotconnect2/index.js:143:23) Dec 13 12:41:18 wizard-iv-fn volumio[936]: at SpotConnEvents.emit (events.js:315:20) Dec 13 12:41:18 wizard-iv-fn volumio[936]: at SpotConnEvents.parseData (/data/plugins/music_service/volspotconnect2/SpotConnController.js:73:16) Dec 13 12:41:18 wizard-iv-fn volumio[936]: at Socket. (/data/plugins/music_service/volspotconnect2/SpotConnController.js:15:12) Dec 13 12:41:18 wizard-iv-fn volumio[936]: at Socket.emit (events.js:315:20) Dec 13 12:41:18 wizard-iv-fn volumio[936]: at UDP.onMessage [as onmessage] (dgram.js:919:8) Dec 13 12:41:18 wizard-iv-fn volumio[936]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 13 12:41:18 wizard-iv-fn volumio[7240]: [Vollibrespot] : Event: GotToken { token: Token { access_token: "BQBzQUlz7QIx0ygtZ1sa20yTc_PQETSNoGybSiZOyUUU8hDULViDyMU6IcCZhxc6MkUXxtOjVtzPUSLCBxn01f8YI8Ip3Y9i0N1M1n61kWWc7zGEQqzJEpHWa4F_Q5yRWGFpgktv8HYeIvc2HxVULnR2T2rnYzRg2rrQXmf3cP6h5sLO0V0ZwlcIKmYvZafDhK1YszSaMW7SY_t7MXKyC22OeM4VHPhHlA4oVFSvEP3doTeviF8nScLA", expires_in: 3600, token_type: "Bearer", scope: ["streaming", "user-modify-playback-state", "user-read-playback-state", "user-read-currently-playing", "user-read-private"] } } Dec 13 12:41:19 wizard-iv-fn volumio[7240]: [Vollibrespot] : (190500 ms) loaded Dec 13 12:41:19 wizard-iv-fn volumio[7240]: [Vollibrespot] : Event: PlaybackStopped { track_id: SpotifyId { id: 6325475104202640983083521896592962587, audio_type: Track } } Dec 13 12:41:19 wizard-iv-fn volumio[7240]: [Vollibrespot] : Resolved 50 tracks from <"spotify:playlist:2FSdLEabZJhi25blbHIGgc"> Dec 13 12:41:20 wizard-iv-fn sudo[11605]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-13 12:40 Dec 13 12:41:20 wizard-iv-fn sudo[11605]: 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="e5f8795c07ba36ff2b02d7ba0a21c7a0f29b897e" VOLUMIO_FE_VERSION="473d8facbed5f9754db84a81b093baace7aa4b64" VOLUMIO_FE3_VERSION="934cddf6d46478e7a53a8d3664cd346f4de7cb34" VOLUMIO_BE_VERSION="359b2717b79cfc72b7d1c918d0fc2885a93bca70" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 24 Jan 2022 10:51:57 AM CET" VOLUMIO_VERSION="3.198" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="74e6a9ad035e912732893c8d6b060b17"