-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Sun 2024-05-12 07:25:45 UTC. -- May 12 07:24:01 volumio wpa_supplicant[680]: wlan0: Failed to initiate sched scan May 12 07:24:08 volumio wpa_supplicant[680]: wlan0: Failed to initiate sched scan May 12 07:24:16 volumio wpa_supplicant[680]: wlan0: Failed to initiate sched scan May 12 07:24:23 volumio wpa_supplicant[680]: wlan0: Failed to initiate sched scan May 12 07:24:31 volumio wpa_supplicant[680]: wlan0: Failed to initiate sched scan May 12 07:24:38 volumio wpa_supplicant[680]: wlan0: Failed to initiate sched scan May 12 07:24:46 volumio wpa_supplicant[680]: wlan0: Failed to initiate sched scan May 12 07:24:53 volumio wpa_supplicant[680]: wlan0: Failed to initiate sched scan May 12 07:25:00 volumio wpa_supplicant[680]: wlan0: Failed to initiate sched scan May 12 07:25:08 volumio wpa_supplicant[680]: wlan0: Failed to initiate sched scan May 12 07:25:15 volumio wpa_supplicant[680]: wlan0: Failed to initiate sched scan May 12 07:25:23 volumio wpa_supplicant[680]: wlan0: Failed to initiate sched scan May 12 07:25:30 volumio wpa_supplicant[680]: wlan0: Failed to initiate sched scan May 12 07:25:38 volumio wpa_supplicant[680]: wlan0: Failed to initiate sched scan May 12 07:25:44 volumio volumio[1470]: [Vollibrespot] : Connecting to AP "ap-gew1.spotify.com:443" May 12 07:25:44 volumio volumio[1470]: [Vollibrespot] : Authenticated as "axgd92bbmko2x1i7drnvnfolc" ! May 12 07:25:44 volumio volumio[1470]: [Vollibrespot] : Setting up new mixer: card:hw:5 mixer:PCM index:0 May 12 07:25:44 volumio volumio[1470]: [Vollibrespot] : Alsa min-db is not SND_CTL_TLV_DB_GAIN_MUTE!! May 12 07:25:44 volumio volumio[1470]: [Vollibrespot] : Alsa Mixer info min: 0 (MilliBel(-12799)[dB]) -- max: 32767 (MilliBel(0)[dB]) HW: true May 12 07:25:44 volumio volumio[1470]: [Vollibrespot] : Using Alsa linear volume May 12 07:25:44 volumio volumio[1470]: [Vollibrespot] : Alsa min-db is not SND_CTL_TLV_DB_GAIN_MUTE!! May 12 07:25:44 volumio volumio[1470]: [Vollibrespot] : Using alsa sink May 12 07:25:44 volumio volumio[1470]: [Vollibrespot] : Country: "RO" May 12 07:25:44 volumio volumio[1470]: [Vollibrespot] : Metadata pipe established May 12 07:25:44 volumio volumio[1470]: [Vollibrespot] : Event: Volume { volume_to_mixer: 13107 } May 12 07:25:44 volumio volumio[785]: [SpotifyConnect] 20 May 12 07:25:44 volumio volumio[785]: [SpotifyConnect] Volume: Spotify:20 Volumio: 20 May 12 07:25:44 volumio volumio[785]: info: CoreStateMachine::pushState May 12 07:25:44 volumio volumio[785]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 12 07:25:44 volumio volumio[785]: info: CoreCommandRouter::volumioPushState May 12 07:25:45 volumio volumio[1470]: [Vollibrespot] : Fetching autoplay context uri May 12 07:25:45 volumio volumio[1470]: [Vollibrespot] : Event: SessionActive { became_active_at: 1715498745592 } May 12 07:25:45 volumio volumio[1470]: [Vollibrespot] : SessionActive! May 12 07:25:45 volumio volumio[1470]: [Vollibrespot] : Event: PlaybackStarted { track_id: SpotifyId { id: 234425130728536359195572677907879753503, audio_type: Track } } May 12 07:25:45 volumio volumio[1470]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 234425130728536359195572677907879753503, audio_type: Track } } May 12 07:25:45 volumio volumio[785]: [SpotifyConnect] A connect session has begun May 12 07:25:45 volumio volumio[785]: [SpotifyConnect] Device palyback is active! May 12 07:25:45 volumio volumio[785]: info: CoreCommandRouter::volumioGetState May 12 07:25:45 volumio volumio[785]: [SpotifyConnect] Currently active: tidalconnect May 12 07:25:45 volumio volumio[785]: [SpotifyConnect] Stopping currently active service May 12 07:25:45 volumio volumio[785]: info: CoreCommandRouter::volumioStop May 12 07:25:45 volumio volumio[785]: info: CoreStateMachine::stop May 12 07:25:45 volumio volumio[785]: info: CoreStateMachine::serviceStop May 12 07:25:45 volumio volumio[785]: info: CoreCommandRouter::serviceStop May 12 07:25:45 volumio volumio[785]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 12 07:25:45 volumio volumio[785]: TypeError: Cannot read property 'then' of undefined May 12 07:25:45 volumio volumio[785]: at SpotConnEvents. (/data/plugins/music_service/volspotconnect2/index.js:143:23) May 12 07:25:45 volumio volumio[785]: at SpotConnEvents.emit (events.js:315:20) May 12 07:25:45 volumio volumio[785]: at SpotConnEvents.parseData (/data/plugins/music_service/volspotconnect2/SpotConnController.js:73:16) May 12 07:25:45 volumio volumio[785]: at Socket. (/data/plugins/music_service/volspotconnect2/SpotConnController.js:15:12) May 12 07:25:45 volumio volumio[785]: at Socket.emit (events.js:315:20) May 12 07:25:45 volumio volumio[785]: at UDP.onMessage [as onmessage] (dgram.js:919:8) May 12 07:25:45 volumio volumio[785]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 12 07:25:45 volumio volumio[1470]: [Vollibrespot] : error 400 for uri hm://autoplay-enabled/query?uri=spotify:search:Mein+kleiner+gr%C3%BCner+Kaktus+The+King's+Singers May 12 07:25:45 volumio volumio[1470]: [Vollibrespot] : AutoplayError: MercuryError May 12 07:25:45 volumio vtcs[1513]: [2024-05-12 07:25:45.639] [tisoc] [warning] [PlaybackControllerImpl.cpp:472] Illegal state:0 May 12 07:25:45 volumio volumio[1470]: [Vollibrespot] : Event: GotToken { token: Token { access_token: "BQA72u5lOnnCCHeZ6Hyo5wy1ggy3wdUiYKgNt6As0sTRwZS8S_0a6BygaJa5MXBqtiuha9jDTF4zUxxb_vhFaSCzu6x5eqWlmeN3DyLT1z3QL4g80TMahw1Y9oFpRI8wbsuQit9tSG_DDCugePcs7mH6VBRNyBgxTfNHLek7cboxkSeuqegGtF0vnm7xLwjlylQ9g04y7nlKE9JmF_6YSpI_-g", expires_in: 3600, token_type: "Bearer", scope: ["streaming", "user-read-playback-state", "user-modify-playback-state", "user-read-currently-playing", "user-read-private"] } } May 12 07:25:45 volumio volumio[1470]: [Vollibrespot] : Loading with Spotify URI May 12 07:25:45 volumio wpa_supplicant[680]: wlan0: Failed to initiate sched scan May 12 07:25:45 volumio sudo[2324]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-12 07:24 May 12 07:25:45 volumio sudo[2324]: 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="9eadfb08439e8c37bef9a38c0641d736c1391e0c" VOLUMIO_FE_VERSION="e6f7875b0e497e33187ca2553d7998c4aeb06af9" VOLUMIO_FE3_VERSION="8d9f9503eeacae3e8f349def90b368b32462d83b" VOLUMIO_BE_VERSION="1cdccc4d006cc26abc6312edbeae58f3081200d4" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Feb 2022 07:16:13 PM CET" VOLUMIO_VERSION="3.233" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="88d67d58bfcedcd908dd7b955a1fa5bd"