-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Sat 2024-11-30 17:29:50 UTC. -- Nov 30 17:28:01 volumio volumio[778]: info: CoreCommandRouter::servicePushState Nov 30 17:28:01 volumio volumio[778]: info: CoreStateMachine::pushState Nov 30 17:28:01 volumio volumio[778]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 30 17:28:01 volumio volumio[778]: info: CoreCommandRouter::volumioPushState Nov 30 17:28:01 volumio volumio[778]: info: CoreCommandRouter::servicePushState Nov 30 17:28:01 volumio volumio[778]: info: CoreStateMachine::pushState Nov 30 17:28:01 volumio volumio[778]: info: CoreCommandRouter::volumioPushState Nov 30 17:28:01 volumio volumio[778]: info: CoreCommandRouter::servicePushState Nov 30 17:28:01 volumio volumio[778]: info: CoreStateMachine::pushState Nov 30 17:28:01 volumio volumio[778]: info: CoreCommandRouter::volumioPushState Nov 30 17:28:01 volumio volumio[778]: info: CoreCommandRouter::servicePushState Nov 30 17:28:01 volumio volumio[778]: info: CoreStateMachine::pushState Nov 30 17:28:01 volumio volumio[778]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 30 17:28:01 volumio volumio[778]: info: CoreCommandRouter::volumioPushState Nov 30 17:29:37 volumio volumio[778]: info: CoreCommandRouter::servicePushState Nov 30 17:29:37 volumio volumio[778]: info: CoreStateMachine::pushState Nov 30 17:29:37 volumio volumio[778]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 30 17:29:37 volumio volumio[778]: info: CoreCommandRouter::volumioPushState Nov 30 17:29:49 volumio volumio[1002]: [Vollibrespot] : Connecting to AP "ap-gew4.spotify.com:443" Nov 30 17:29:49 volumio volumio[1002]: [Vollibrespot] : Authenticated as "ly2fmohct0c8sla7duzk7cg3b" ! Nov 30 17:29:49 volumio volumio[1002]: [Vollibrespot] : Using alsa sink Nov 30 17:29:49 volumio volumio[1002]: [Vollibrespot] : Metadata pipe established Nov 30 17:29:49 volumio volumio[1002]: [Vollibrespot] : Country: "PL" Nov 30 17:29:49 volumio volumio[1002]: [Vollibrespot] : Event: Volume { volume_to_mixer: 65535 } Nov 30 17:29:49 volumio volumio[778]: [SpotifyConnect] Volume Spotify: 100 Volumio: 100 Nov 30 17:29:50 volumio volumio[1002]: [Vollibrespot] : Fetching autoplay context uri Nov 30 17:29:50 volumio volumio[1002]: [Vollibrespot] : Event: SessionActive { became_active_at: 1732987790188 } Nov 30 17:29:50 volumio volumio[1002]: [Vollibrespot] : SessionActive! Nov 30 17:29:50 volumio volumio[1002]: [Vollibrespot] : Event: PlaybackStarted { track_id: SpotifyId { id: 50282746473879527318048159182615419586, audio_type: Track } } Nov 30 17:29:50 volumio volumio[1002]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 50282746473879527318048159182615419586, audio_type: Track } } Nov 30 17:29:50 volumio volumio[778]: [SpotifyConnect] A connect session has begun Nov 30 17:29:50 volumio volumio[778]: [SpotifyConnect] Device palyback is active! Nov 30 17:29:50 volumio volumio[778]: info: CoreCommandRouter::volumioGetState Nov 30 17:29:50 volumio volumio[778]: [SpotifyConnect] Currently active: tidalconnect Nov 30 17:29:50 volumio volumio[778]: info: CoreCommandRouter::volumioStop Nov 30 17:29:50 volumio volumio[778]: info: CoreStateMachine::stop Nov 30 17:29:50 volumio volumio[778]: info: CoreStateMachine::serviceStop Nov 30 17:29:50 volumio volumio[778]: info: CoreCommandRouter::serviceStop Nov 30 17:29:50 volumio volumio[778]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 30 17:29:50 volumio volumio[778]: TypeError: Cannot read property 'then' of undefined Nov 30 17:29:50 volumio volumio[778]: at SpotConnEvents. (/data/plugins/music_service/spop/index.js:2034:27) Nov 30 17:29:50 volumio volumio[778]: at SpotConnEvents.emit (events.js:315:20) Nov 30 17:29:50 volumio volumio[778]: at SpotConnEvents.parseData (/data/plugins/music_service/spop/SpotConnController.js:73:16) Nov 30 17:29:50 volumio volumio[778]: at Socket. (/data/plugins/music_service/spop/SpotConnController.js:15:12) Nov 30 17:29:50 volumio volumio[778]: at Socket.emit (events.js:315:20) Nov 30 17:29:50 volumio volumio[778]: at UDP.onMessage [as onmessage] (dgram.js:919:8) Nov 30 17:29:50 volumio volumio[778]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 30 17:29:50 volumio vtcs[1385]: [2024-11-30 17:29:50.220] [tisoc] [warning] [PlaybackControllerImpl.cpp:472] Illegal state:0 Nov 30 17:29:50 volumio volumio[1002]: [Vollibrespot] : Autoplay uri resolved to <"spotify:station:playlist:0WfSmwoNJ0kPt2lJfKD7nO"> Nov 30 17:29:50 volumio volumio[1002]: [Vollibrespot] : Resolving uri "spotify:station:playlist:0WfSmwoNJ0kPt2lJfKD7nO" Nov 30 17:29:50 volumio volumio[1002]: [Vollibrespot] : Event: GotToken { token: Token { access_token: "BQBGQvx5jNk9SHr03jhgkxZeLK5rWG8gAGk-fr1jnd-X0bLAKwvzzfZosmhYJvzWamhRxx1YhvozT1vWssATwjZgstQ__iTfJVjHFYYIBYmM7uc7zCq2aW6fWZvGV4toiJ6MrUsKxtuoUZ7rEq8qO37LEu5Zvis3OgfhgSyA09AblwmX9P04povFQcArDjD7ulWF62Wp2YCJkmsm94GgfkE7Y9W-MjwQdg", expires_in: 3600, token_type: "Bearer", scope: ["streaming", "user-read-playback-state", "user-modify-playback-state", "user-read-currently-playing", "user-read-private"] } } Nov 30 17:29:50 volumio volumio[1002]: [Vollibrespot] : Loading with Spotify URI Nov 30 17:29:50 volumio vtcs[1385]: [2024-11-30 17:29:50] [info] asio async_shutdown error: asio.misc:2 (End of file) Nov 30 17:29:50 volumio volumio[1002]: [Vollibrespot] : Resolved 50 tracks from <"spotify:playlist:0WfSmwoNJ0kPt2lJfKD7nO"> Nov 30 17:29:50 volumio volumio[1002]: [Vollibrespot] : (296906 ms) loaded Nov 30 17:29:50 volumio volumio[1002]: [Vollibrespot] : Event: PlaybackStopped { track_id: SpotifyId { id: 50282746473879527318048159182615419586, audio_type: Track } } Nov 30 17:29:50 volumio sudo[13306]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-30 17:28 Nov 30 17:29:50 volumio sudo[13306]: 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="3cfa1502993c2310fc263d6ebda1707071fe4cc2" VOLUMIO_FE_VERSION="8efe4f08f83e5de574651b8ed2333629c140d365" VOLUMIO_FE3_VERSION="8f1d07fcde806ea34f52cff908ec76359e14970e" VOLUMIO_BE_VERSION="e249dfa388452e6b8443121dc9b4cabca459c531" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 09 Jun 2022 05:55:51 PM CEST" VOLUMIO_VERSION="3.301" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="6a8974e587cd4bab189d231f79b65462"