-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Sat 2024-04-13 15:32:42 UTC. -- Apr 13 15:32:37 volumio-gp volumio[893]: info: CoreCommandRouter::servicePushState Apr 13 15:32:37 volumio-gp volumio[893]: info: CoreStateMachine::pushState Apr 13 15:32:37 volumio-gp volumio[893]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 13 15:32:37 volumio-gp volumio[893]: info: CoreCommandRouter::volumioPushState Apr 13 15:32:41 volumio-gp volumio[1022]: [Vollibrespot] : Connecting to AP "ap-gew1.spotify.com:443" Apr 13 15:32:41 volumio-gp volumio[1022]: [Vollibrespot] : Authenticated as "31x5tjvidsoszdh24rdwt6fh7zhm" ! Apr 13 15:32:41 volumio-gp volumio[1022]: [Vollibrespot] : Using alsa sink Apr 13 15:32:41 volumio-gp volumio[1022]: [Vollibrespot] : Metadata pipe established Apr 13 15:32:41 volumio-gp volumio[1022]: [Vollibrespot] : Event: Volume { volume_to_mixer: 55050 } Apr 13 15:32:41 volumio-gp volumio[1022]: [Vollibrespot] : Country: "PT" Apr 13 15:32:41 volumio-gp volumio[893]: [SpotifyConnect] Volume Spotify: 84.0009155413138 Volumio: 84 Apr 13 15:32:42 volumio-gp volumio[1022]: [Vollibrespot] : Fetching autoplay context uri Apr 13 15:32:42 volumio-gp volumio[1022]: [Vollibrespot] : Event: SessionActive { became_active_at: 1713022362307 } Apr 13 15:32:42 volumio-gp volumio[1022]: [Vollibrespot] : SessionActive! Apr 13 15:32:42 volumio-gp volumio[1022]: [Vollibrespot] : Event: PlaybackStarted { track_id: SpotifyId { id: 109463673064290640708684375899310891479, audio_type: Track } } Apr 13 15:32:42 volumio-gp volumio[1022]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 109463673064290640708684375899310891479, audio_type: Track } } Apr 13 15:32:42 volumio-gp volumio[893]: [SpotifyConnect] A connect session has begun Apr 13 15:32:42 volumio-gp volumio[893]: info: Acquiring new spotify session Apr 13 15:32:42 volumio-gp volumio[893]: info: CoreCommandRouter::volumioStop Apr 13 15:32:42 volumio-gp volumio[893]: info: CoreStateMachine::stop Apr 13 15:32:42 volumio-gp volumio[893]: info: CoreStateMachine::serviceStop Apr 13 15:32:42 volumio-gp volumio[893]: info: CoreCommandRouter::serviceStop Apr 13 15:32:42 volumio-gp volumio[893]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 13 15:32:42 volumio-gp volumio[893]: TypeError: Cannot read property 'then' of undefined Apr 13 15:32:42 volumio-gp volumio[893]: at SpotConnEvents. (/data/plugins/music_service/spop/index.js:2111:31) Apr 13 15:32:42 volumio-gp volumio[893]: at SpotConnEvents.emit (events.js:315:20) Apr 13 15:32:42 volumio-gp volumio[893]: at SpotConnEvents.parseData (/data/plugins/music_service/spop/SpotConnController.js:81:16) Apr 13 15:32:42 volumio-gp volumio[893]: at Socket. (/data/plugins/music_service/spop/SpotConnController.js:15:12) Apr 13 15:32:42 volumio-gp volumio[893]: at Socket.emit (events.js:315:20) Apr 13 15:32:42 volumio-gp volumio[893]: at UDP.onMessage [as onmessage] (dgram.js:919:8) Apr 13 15:32:42 volumio-gp volumio[893]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 13 15:32:42 volumio-gp vtcs[1308]: [2024-04-13 15:32:42] [info] asio async_shutdown error: asio.misc:2 (End of file) Apr 13 15:32:42 volumio-gp volumio[1022]: [Vollibrespot] : error 400 for uri hm://autoplay-enabled/query?uri=spotify:search:halloween+crescer Apr 13 15:32:42 volumio-gp volumio[1022]: [Vollibrespot] : AutoplayError: MercuryError Apr 13 15:32:42 volumio-gp volumio[1022]: [Vollibrespot] : Event: GotToken { token: Token { access_token: "BQD1Mcd1K4pVCkstE_QT_MfdbMz-_G6l1v9WIEWvORvAhWsgc1etPcISk1LPVhIfaMMblL-aPs5txGi1lQFYmzTL46Olhhv9CVzxmG27XMTgjxRDujsdqtx6wwijx0WFllMmChlBhDV33kD3ueRsFD3nYdAaE2wPRuWHgoZJOlIeIt8nRVL8I_hyFQzYZx75GWzAyUnbFeWsGyIJicR4RTVXwSinqA", expires_in: 3600, token_type: "Bearer", scope: ["streaming", "user-read-playback-state", "user-modify-playback-state", "user-read-currently-playing", "user-read-private"] } } Apr 13 15:32:42 volumio-gp volumio[1022]: [Vollibrespot] : Loading with Spotify URI Apr 13 15:32:42 volumio-gp vtcs[1308]: [2024-04-13 15:32:42.620] [tisoc] [warning] [PlaybackControllerImpl.cpp:501] Illegal state:0 Apr 13 15:32:42 volumio-gp sudo[2218]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-13 15:31 Apr 13 15:32:42 volumio-gp sudo[2218]: 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="f8baf7ad070f376535a93fcc6590774528ddb153" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="0e3cf2fcfe1906c5090fab62b4cc2a5ff0069fd8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 30 Jan 2024 03:58:37 PM CET" VOLUMIO_VERSION="3.611" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="1d6379ebb89c40061afa8aee78176887"