-- Logs begin at Fri 2024-05-03 18:12:27 CEST, end at Sat 2024-05-04 18:07:39 CEST. -- May 04 18:06:08 volumio3 volumio[895]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 04 18:06:08 volumio3 volumio[895]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 04 18:06:08 volumio3 volumio[895]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 04 18:06:08 volumio3 volumio[895]: info: Received Get System Version May 04 18:06:08 volumio3 volumio[895]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 04 18:06:08 volumio3 volumio[895]: info: Received Get System Info May 04 18:06:08 volumio3 volumio[895]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 04 18:06:08 volumio3 volumio[895]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 04 18:06:08 volumio3 volumio[895]: info: Discovery: Getting this device information May 04 18:06:08 volumio3 volumio[895]: info: CoreCommandRouter::volumioGetState May 04 18:06:08 volumio3 volumio[895]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 04 18:07:38 volumio3 volumio[1911]: [Vollibrespot] : Connecting to AP "ap-gew4.spotify.com:443" May 04 18:07:38 volumio3 volumio[1911]: [Vollibrespot] : Authenticated as "hajdukveljko" ! May 04 18:07:38 volumio3 volumio[1911]: [Vollibrespot] : Setting up new mixer: card:hw:5 mixer:Analogue 18 index:0 May 04 18:07:38 volumio3 volumio[1911]: [Vollibrespot] : Alsa min-db is not SND_CTL_TLV_DB_GAIN_MUTE!! May 04 18:07:38 volumio3 volumio[1911]: [Vollibrespot] : Alsa Mixer info min: 0 (MilliBel(-12750)[dB]) -- max: 255 (MilliBel(0)[dB]) HW: true May 04 18:07:38 volumio3 volumio[1911]: [Vollibrespot] : Alsa min-db is not SND_CTL_TLV_DB_GAIN_MUTE!! May 04 18:07:38 volumio3 volumio[1911]: [Vollibrespot] : Using alsa sink May 04 18:07:38 volumio3 volumio[1911]: [Vollibrespot] : Country: "SE" May 04 18:07:38 volumio3 volumio[1911]: [Vollibrespot] : Metadata pipe established May 04 18:07:38 volumio3 volumio[1911]: [Vollibrespot] : Event: Volume { volume_to_mixer: 0 } May 04 18:07:38 volumio3 volumio[895]: [SpotifyConnect] 0 May 04 18:07:38 volumio3 volumio[895]: [SpotifyConnect] Volume: Spotify:0 Volumio: 0 May 04 18:07:38 volumio3 volumio[895]: info: CoreStateMachine::pushState May 04 18:07:38 volumio3 volumio[895]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 18:07:38 volumio3 volumio[895]: info: CoreCommandRouter::volumioPushState May 04 18:07:38 volumio3 volumio[895]: info: MRS: Pushing multiroomSync output update for this device May 04 18:07:38 volumio3 volumio[895]: info: MRS: Pushing multiroomSync output May 04 18:07:39 volumio3 volumio[1911]: [Vollibrespot] : Fetching autoplay context uri May 04 18:07:39 volumio3 volumio[1911]: [Vollibrespot] : Event: SessionActive { became_active_at: 1714838858999 } May 04 18:07:39 volumio3 volumio[1911]: [Vollibrespot] : SessionActive! May 04 18:07:39 volumio3 volumio[1911]: [Vollibrespot] : Event: PlaybackStarted { track_id: SpotifyId { id: 229914446086461472644320333507666582393, audio_type: Track } } May 04 18:07:39 volumio3 volumio[1911]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 229914446086461472644320333507666582393, audio_type: Track } } May 04 18:07:39 volumio3 volumio[895]: [SpotifyConnect] A connect session has begun May 04 18:07:39 volumio3 volumio[895]: [SpotifyConnect] Device palyback is active! May 04 18:07:39 volumio3 volumio[895]: info: CoreCommandRouter::volumioGetState May 04 18:07:39 volumio3 volumio[895]: [SpotifyConnect] Currently active: tidalconnect May 04 18:07:39 volumio3 volumio[895]: [SpotifyConnect] Stopping currently active service May 04 18:07:39 volumio3 volumio[895]: info: CoreCommandRouter::volumioStop May 04 18:07:39 volumio3 volumio[895]: info: CoreStateMachine::stop May 04 18:07:39 volumio3 volumio[895]: info: CoreStateMachine::serviceStop May 04 18:07:39 volumio3 volumio[895]: info: CoreCommandRouter::serviceStop May 04 18:07:39 volumio3 volumio[895]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 04 18:07:39 volumio3 volumio[895]: TypeError: Cannot read property 'then' of undefined May 04 18:07:39 volumio3 volumio[895]: at SpotConnEvents. (/data/plugins/music_service/volspotconnect2/index.js:143:23) May 04 18:07:39 volumio3 volumio[895]: at SpotConnEvents.emit (events.js:315:20) May 04 18:07:39 volumio3 volumio[895]: at SpotConnEvents.parseData (/data/plugins/music_service/volspotconnect2/SpotConnController.js:73:16) May 04 18:07:39 volumio3 volumio[895]: at Socket. (/data/plugins/music_service/volspotconnect2/SpotConnController.js:15:12) May 04 18:07:39 volumio3 volumio[895]: at Socket.emit (events.js:315:20) May 04 18:07:39 volumio3 volumio[895]: at UDP.onMessage [as onmessage] (dgram.js:919:8) May 04 18:07:39 volumio3 volumio[895]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 04 18:07:39 volumio3 volumio[1911]: [Vollibrespot] : Autoplay uri resolved to <"spotify:station:album:22SC0qXkZMSZn1LfOAzvl3"> May 04 18:07:39 volumio3 volumio[1911]: [Vollibrespot] : Resolving uri "spotify:station:album:22SC0qXkZMSZn1LfOAzvl3" May 04 18:07:39 volumio3 volumio[1911]: [Vollibrespot] : Event: GotToken { token: Token { access_token: "BQC4oOuDAzyXw7jydjOpia51LPmgXnyTOo2Das6ieAmxPFIab7KUhCk8DFgck3Dzy_3RFXqiMsvFGAMnNqmEy1C6SyLY9JKFo6Z5yMps-OAeJSHOeE3Xu7TSoAYGq31IbnfjqKl-N30cvPJkFS98qISaqoJxsOteSXHZ0V9nDN38yjg0EUdl038xZ-WvsUaMNoaLzvty", expires_in: 3600, token_type: "Bearer", scope: ["streaming", "user-read-playback-state", "user-modify-playback-state", "user-read-currently-playing", "user-read-private"] } } May 04 18:07:39 volumio3 volumio[1911]: [Vollibrespot] : Loading with Spotify URI May 04 18:07:39 volumio3 sudo[30112]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-04 18:06 May 04 18:07:39 volumio3 sudo[30112]: 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="b51559ff3f3719e35ff52cb9ccf2ff401b5ecb06" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="9f81dc1810002a5ad8861a42d98d57bc06a5d5d3" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 17 Apr 2024 07:54:00 PM CEST" VOLUMIO_VERSION="3.649" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="9c6dee1ade565aed57d77a70a260b988"