-- Logs begin at Sat 2023-12-23 17:26:50 UTC, end at Thu 2024-04-11 17:51:42 UTC. -- Apr 11 17:50:13 volumio-project vtcs[11419]: [2024-04-11 17:50:13] [info] asio async_shutdown error: asio.misc:2 (End of file) Apr 11 17:50:25 volumio-project volumio[11382]: [Vollibrespot] : couldn't parse packet from 192.168.32.173:5353: type 47 is invalid Apr 11 17:50:32 volumio-project volumio[648]: info: Pushing metadata Apr 11 17:50:32 volumio-project volumio[648]: info: CoreCommandRouter::servicePushState Apr 11 17:50:32 volumio-project volumio[648]: info: CoreStateMachine::pushState Apr 11 17:50:32 volumio-project volumio[648]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 17:50:32 volumio-project volumio[648]: info: CoreCommandRouter::volumioPushState Apr 11 17:50:32 volumio-project volumio[648]: info: CoreCommandRouter::servicePushState Apr 11 17:50:32 volumio-project volumio[648]: info: CoreStateMachine::pushState Apr 11 17:50:32 volumio-project volumio[648]: info: CoreCommandRouter::volumioPushState Apr 11 17:50:54 volumio-project volumio[11382]: [Vollibrespot] : couldn't parse packet from 192.168.32.173:5353: type 47 is invalid Apr 11 17:51:12 volumio-project volumio[648]: info: CoreCommandRouter::servicePushState Apr 11 17:51:12 volumio-project volumio[648]: info: CoreStateMachine::pushState Apr 11 17:51:12 volumio-project volumio[648]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 17:51:12 volumio-project volumio[648]: info: CoreCommandRouter::volumioPushState Apr 11 17:51:14 volumio-project vtcs[11419]: [2024-04-11 17:51:14] [info] asio async_shutdown error: asio.misc:2 (End of file) Apr 11 17:51:23 volumio-project volumio[11382]: [Vollibrespot] : couldn't parse packet from 192.168.32.173:5353: type 47 is invalid Apr 11 17:51:40 volumio-project volumio[11382]: [Vollibrespot] : Connecting to AP "ap-gew1.spotify.com:443" Apr 11 17:51:41 volumio-project volumio[11382]: [Vollibrespot] : Authenticated as "augustinasvaliukevicius" ! Apr 11 17:51:41 volumio-project volumio[11382]: [Vollibrespot] : Using alsa sink Apr 11 17:51:41 volumio-project volumio[11382]: [Vollibrespot] : Metadata pipe established Apr 11 17:51:41 volumio-project volumio[11382]: [Vollibrespot] : Country: "LT" Apr 11 17:51:41 volumio-project volumio[11382]: [Vollibrespot] : Event: Volume { volume_to_mixer: 65535 } Apr 11 17:51:41 volumio-project volumio[648]: [SpotifyConnect] Volume Spotify: 100 Volumio: 100 Apr 11 17:51:41 volumio-project volumio[11382]: [Vollibrespot] : Fetching autoplay context uri Apr 11 17:51:41 volumio-project volumio[11382]: [Vollibrespot] : Event: SessionActive { became_active_at: 1712857901800 } Apr 11 17:51:41 volumio-project volumio[11382]: [Vollibrespot] : SessionActive! Apr 11 17:51:41 volumio-project volumio[11382]: [Vollibrespot] : Event: PlaybackStarted { track_id: SpotifyId { id: 139010563506984831455192180420796140503, audio_type: Track } } Apr 11 17:51:41 volumio-project volumio[11382]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 139010563506984831455192180420796140503, audio_type: Track } } Apr 11 17:51:41 volumio-project volumio[648]: [SpotifyConnect] A connect session has begun Apr 11 17:51:41 volumio-project volumio[648]: info: Acquiring new spotify session Apr 11 17:51:41 volumio-project volumio[648]: info: CoreCommandRouter::volumioStop Apr 11 17:51:41 volumio-project volumio[648]: info: CoreStateMachine::stop Apr 11 17:51:41 volumio-project volumio[648]: info: CoreStateMachine::serviceStop Apr 11 17:51:41 volumio-project volumio[648]: info: CoreCommandRouter::serviceStop Apr 11 17:51:41 volumio-project volumio[648]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 11 17:51:41 volumio-project volumio[648]: TypeError: Cannot read property 'then' of undefined Apr 11 17:51:41 volumio-project volumio[648]: at SpotConnEvents. (/data/plugins/music_service/spop/index.js:2111:31) Apr 11 17:51:41 volumio-project volumio[648]: at SpotConnEvents.emit (events.js:315:20) Apr 11 17:51:41 volumio-project volumio[648]: at SpotConnEvents.parseData (/data/plugins/music_service/spop/SpotConnController.js:81:16) Apr 11 17:51:41 volumio-project volumio[648]: at Socket. (/data/plugins/music_service/spop/SpotConnController.js:15:12) Apr 11 17:51:41 volumio-project volumio[648]: at Socket.emit (events.js:315:20) Apr 11 17:51:41 volumio-project volumio[648]: at UDP.onMessage [as onmessage] (dgram.js:919:8) Apr 11 17:51:41 volumio-project volumio[648]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 11 17:51:41 volumio-project vtcs[11419]: [2024-04-11 17:51:41.811] [tisoc] [warning] [PlaybackControllerImpl.cpp:472] Illegal state:0 Apr 11 17:51:41 volumio-project volumio[11382]: [Vollibrespot] : error 400 for uri hm://autoplay-enabled/query?uri=spotify:search:Angela+(Theme+From+%22Taxi%22)+(Bottom+Line)+Bob+James Apr 11 17:51:41 volumio-project volumio[11382]: [Vollibrespot] : AutoplayError: MercuryError Apr 11 17:51:41 volumio-project volumio[11382]: [Vollibrespot] : Event: GotToken { token: Token { access_token: "BQBRIPPFz5k5Lv_OqfZmtB7SOysemQ9D4YK3raqbFQy53lM8D8dHjRN_yMs1wGspWTM5wSJXP4enhahxfnLYqFFKmMYiB9Qs7nEp1akS2Yv53vdQVfUiSJBM0rzzLqWYetiGGmWd5_mfqb_J0I1QZyOi0QrL6ceGJxMiGK7F1J94S0oUtYqfzpaYEoWvlfTKb7gd1M7BTPtCMM_3WiMe8Fg", expires_in: 3600, token_type: "Bearer", scope: ["streaming", "user-read-playback-state", "user-modify-playback-state", "user-read-currently-playing", "user-read-private"] } } Apr 11 17:51:41 volumio-project volumio[11382]: [Vollibrespot] : Loading with Spotify URI Apr 11 17:51:42 volumio-project sudo[13190]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-11 17:50 Apr 11 17:51:42 volumio-project sudo[13190]: 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"