-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Sat 2024-05-11 19:58:23 CEST. -- May 11 19:57:27 volumio volumio[989]: info: CoreCommandRouter::servicePushState May 11 19:57:27 volumio volumio[989]: info: CoreStateMachine::pushState May 11 19:57:27 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 11 19:57:27 volumio volumio[989]: info: CoreCommandRouter::volumioPushState May 11 19:57:27 volumio volumio[989]: info: CoreCommandRouter::servicePushState May 11 19:57:27 volumio volumio[989]: info: CoreStateMachine::pushState May 11 19:57:27 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 11 19:57:27 volumio volumio[989]: info: CoreCommandRouter::volumioPushState May 11 19:57:37 volumio volumio[989]: info: CoreCommandRouter::servicePushState May 11 19:57:37 volumio volumio[989]: info: CoreStateMachine::pushState May 11 19:57:37 volumio volumio[989]: info: CoreCommandRouter::volumioPushState May 11 19:57:37 volumio volumio[989]: info: CoreCommandRouter::servicePushState May 11 19:57:37 volumio volumio[989]: info: CoreStateMachine::pushState May 11 19:57:37 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 11 19:57:37 volumio volumio[989]: info: CoreCommandRouter::volumioPushState May 11 19:57:42 volumio volumio[989]: info: CoreCommandRouter::servicePushState May 11 19:57:42 volumio volumio[989]: info: CoreStateMachine::pushState May 11 19:57:42 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 11 19:57:42 volumio volumio[989]: info: CoreCommandRouter::volumioPushState May 11 19:57:50 volumio volumio[989]: info: CoreCommandRouter::servicePushState May 11 19:57:50 volumio volumio[989]: info: CoreStateMachine::pushState May 11 19:57:50 volumio volumio[989]: info: CoreCommandRouter::volumioPushState May 11 19:57:50 volumio volumio[989]: info: CoreCommandRouter::servicePushState May 11 19:57:50 volumio volumio[989]: info: CoreStateMachine::pushState May 11 19:57:50 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 11 19:57:50 volumio volumio[989]: info: CoreCommandRouter::volumioPushState May 11 19:57:53 volumio vtcs[2149]: [2024-05-11 19:57:53] [warning] got non-close frame in state closing May 11 19:57:57 volumio vtcs[2149]: [2024-05-11 19:57:57] [warning] got non-close frame in state closing May 11 19:57:57 volumio vtcs[2149]: [2024-05-11 19:57:57] [warning] got frame in state closed May 11 19:57:57 volumio vtcs[2149]: [2024-05-11 19:57:57] [info] asio async_shutdown error: asio.ssl.stream:1 (stream truncated) May 11 19:58:00 volumio vtcs[2149]: [2024-05-11 19:58:00] [error] handle_read_frame error: asio.ssl.stream:1 (stream truncated) May 11 19:58:01 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 11 19:58:01 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 11 19:58:01 volumio volumio[989]: info: Discovery: Getting this device information May 11 19:58:01 volumio volumio[989]: info: CoreCommandRouter::volumioGetState May 11 19:58:01 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 11 19:58:01 volumio volumio[989]: verbose: New Socket.io Connection to 192.168.178.68 from 192.168.178.47 UA: unknown Total Clients: 7 May 11 19:58:01 volumio volumio[989]: info: CoreCommandRouter::volumioGetState May 11 19:58:01 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 11 19:58:01 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 11 19:58:22 volumio volumio[2057]: [Vollibrespot] : Event: SessionActive { became_active_at: 1715450302629 } May 11 19:58:22 volumio volumio[2057]: [Vollibrespot] : SessionActive! May 11 19:58:22 volumio volumio[2057]: [Vollibrespot] : Fetching autoplay context uri May 11 19:58:22 volumio volumio[2057]: [Vollibrespot] : Event: PlaybackStarted { track_id: SpotifyId { id: 176187787131678487415235790982730884993, audio_type: Track } } May 11 19:58:22 volumio volumio[2057]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 176187787131678487415235790982730884993, audio_type: Track } } May 11 19:58:22 volumio volumio[989]: [SpotifyConnect] A connect session has begun May 11 19:58:22 volumio volumio[989]: info: Acquiring new spotify session May 11 19:58:22 volumio volumio[989]: info: CoreCommandRouter::volumioStop May 11 19:58:22 volumio volumio[989]: info: CoreStateMachine::stop May 11 19:58:22 volumio volumio[989]: info: CoreStateMachine::serviceStop May 11 19:58:22 volumio volumio[989]: info: CoreCommandRouter::serviceStop May 11 19:58:22 volumio volumio[989]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 11 19:58:22 volumio volumio[989]: TypeError: Cannot read property 'then' of undefined May 11 19:58:22 volumio volumio[989]: at SpotConnEvents. (/data/plugins/music_service/spop/index.js:2111:31) May 11 19:58:22 volumio volumio[989]: at SpotConnEvents.emit (events.js:400:28) May 11 19:58:22 volumio volumio[989]: at SpotConnEvents.parseData (/data/plugins/music_service/spop/SpotConnController.js:81:16) May 11 19:58:22 volumio volumio[989]: at Socket. (/data/plugins/music_service/spop/SpotConnController.js:15:12) May 11 19:58:22 volumio volumio[989]: at Socket.emit (events.js:400:28) May 11 19:58:22 volumio volumio[989]: at UDP.onMessage [as onmessage] (dgram.js:931:8) May 11 19:58:22 volumio volumio[989]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 11 19:58:22 volumio vtcs[2149]: [2024-05-11 19:58:22.654] [tisoc] [warning] [PlaybackControllerImpl.cpp:472] Illegal state:0 May 11 19:58:22 volumio volumio[2057]: [Vollibrespot] : Autoplay uri resolved to <"spotify:station:playlist:2kLzU0U84RGdyDOWSccntt"> May 11 19:58:22 volumio volumio[2057]: [Vollibrespot] : Resolving uri "spotify:station:playlist:2kLzU0U84RGdyDOWSccntt" May 11 19:58:22 volumio volumio[2057]: [Vollibrespot] : Event: GotToken { token: Token { access_token: "BQB5wfqMcbvPQ6hvLyVFCx5iRy6XB_M2-C2LHlO0cuUo0W7s11Nsh7kKtlbTUVemmPtgtI_HwwL4PY6i7Cs2_JP3zIHvi-F4IuA2f9burtPeSWGMr-yIjH6jDDVy2EM4d6TOoZeabjDF5mp73_DZ4KvQLB5IpGsm5wYM1olms-k3uZA7eAqNiKbn6EQaVOX5", expires_in: 3600, token_type: "Bearer", scope: ["streaming", "user-read-playback-state", "user-modify-playback-state", "user-read-currently-playing", "user-read-private"] } } May 11 19:58:22 volumio volumio[2057]: [Vollibrespot] : Loading with Spotify URI May 11 19:58:23 volumio sudo[4130]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-11 19:57 May 11 19:58:23 volumio sudo[4130]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="b1c3cf61c2a0027c66bab1eb0a3795f80c1f2e95" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="e3eb3ee5b16063502f3a1735e11a28cfe54a0f46" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sun 25 Jun 2023 05:52:14 PM CEST" VOLUMIO_VERSION="3.512" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_DEVICENAME="Asus Tinkerboard" VOLUMIO_HASH="3a590d7744c5e179bbe7c45ae4d4612f"