-- Logs begin at Thu 2024-03-07 06:28:32 UTC, end at Sat 2024-04-20 13:04:21 UTC. -- Apr 20 13:03:24 volumio-eddy volumiologrotate[472]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Apr 20 13:03:24 volumio-eddy volumiologrotate[472]: ls: cannot access 'EDDY': No such file or directory Apr 20 13:04:20 volumio-eddy volumio[3639]: [Vollibrespot] : Connecting to AP "ap-gew4.spotify.com:443" Apr 20 13:04:21 volumio-eddy volumio[3639]: [Vollibrespot] : Authenticated as "31gydf5ifzpqbzxuacce3z7my3sa" ! Apr 20 13:04:21 volumio-eddy volumio[3639]: [Vollibrespot] : Using alsa sink Apr 20 13:04:21 volumio-eddy volumio[3639]: [Vollibrespot] : Metadata pipe established Apr 20 13:04:21 volumio-eddy volumio[3639]: [Vollibrespot] : Country: "NL" Apr 20 13:04:21 volumio-eddy volumio[3639]: [Vollibrespot] : Event: Volume { volume_to_mixer: 65535 } Apr 20 13:04:21 volumio-eddy volumio[1102]: [SpotifyConnect] Volume Spotify: 100 Volumio: 100 Apr 20 13:04:21 volumio-eddy volumio[3639]: [Vollibrespot] : Fetching autoplay context uri Apr 20 13:04:21 volumio-eddy volumio[3639]: [Vollibrespot] : Event: SessionActive { became_active_at: 1713618261470 } Apr 20 13:04:21 volumio-eddy volumio[3639]: [Vollibrespot] : SessionActive! Apr 20 13:04:21 volumio-eddy volumio[3639]: [Vollibrespot] : Event: PlaybackStarted { track_id: SpotifyId { id: 324349925774051840377574505977356668554, audio_type: Track } } Apr 20 13:04:21 volumio-eddy volumio[3639]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 324349925774051840377574505977356668554, audio_type: Track } } Apr 20 13:04:21 volumio-eddy volumio[1102]: [SpotifyConnect] A connect session has begun Apr 20 13:04:21 volumio-eddy volumio[1102]: info: Acquiring new spotify session Apr 20 13:04:21 volumio-eddy volumio[1102]: info: CoreCommandRouter::volumioStop Apr 20 13:04:21 volumio-eddy volumio[1102]: info: CoreStateMachine::stop Apr 20 13:04:21 volumio-eddy volumio[1102]: info: CoreStateMachine::serviceStop Apr 20 13:04:21 volumio-eddy volumio[1102]: info: CoreCommandRouter::serviceStop Apr 20 13:04:21 volumio-eddy volumio[1102]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 20 13:04:21 volumio-eddy volumio[1102]: TypeError: Cannot read property 'then' of undefined Apr 20 13:04:21 volumio-eddy volumio[1102]: at SpotConnEvents. (/data/plugins/music_service/spop/index.js:2111:31) Apr 20 13:04:21 volumio-eddy volumio[1102]: at SpotConnEvents.emit (events.js:400:28) Apr 20 13:04:21 volumio-eddy volumio[1102]: at SpotConnEvents.parseData (/data/plugins/music_service/spop/SpotConnController.js:81:16) Apr 20 13:04:21 volumio-eddy volumio[1102]: at Socket. (/data/plugins/music_service/spop/SpotConnController.js:15:12) Apr 20 13:04:21 volumio-eddy volumio[1102]: at Socket.emit (events.js:400:28) Apr 20 13:04:21 volumio-eddy volumio[1102]: at UDP.onMessage [as onmessage] (dgram.js:931:8) Apr 20 13:04:21 volumio-eddy volumio[1102]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 20 13:04:21 volumio-eddy volumio[3639]: [Vollibrespot] : Autoplay uri resolved to <"spotify:station:playlist:3bLIqNxqttMiruZa77F4LD"> Apr 20 13:04:21 volumio-eddy volumio[3639]: [Vollibrespot] : Resolving uri "spotify:station:playlist:3bLIqNxqttMiruZa77F4LD" Apr 20 13:04:21 volumio-eddy volumio[3639]: [Vollibrespot] : Event: GotToken { token: Token { access_token: "BQCD91PwMK2h1aS19cz-CMRnrRj80B46XiZMGHOodfumzMAYl5gU1IUh8W4GSfYKPmKbtSIM2x_b4b7zx4JZG2P29UXtfLx0yRGi9HrMOtzfAgTS2OJXnzhn7xXnpNwovRXpOpDqAF6DgONMwNzFByre052KsEihT8uvWJNzLHgNaKf30OgDH5u7pln3kNQA56yOVh86qNdvgeGls_tAVP0y202NcQ", expires_in: 3600, token_type: "Bearer", scope: ["streaming", "user-read-playback-state", "user-modify-playback-state", "user-read-currently-playing", "user-read-private"] } } Apr 20 13:04:21 volumio-eddy volumio[3639]: [Vollibrespot] : Loading with Spotify URI Apr 20 13:04:21 volumio-eddy vtcs[3707]: [2024-04-20 13:04:21.695] [tisoc] [warning] [PlaybackControllerImpl.cpp:501] Illegal state:0 Apr 20 13:04:21 volumio-eddy sudo[11105]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-20 13:03 Apr 20 13:04:21 volumio-eddy sudo[11105]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 13:04:21 volumio-eddy volumio[3639]: [Vollibrespot] : (183773 ms) loaded 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="e831866afe2db61aee9333a8f5ba9d9fd36609c7" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sun 18 Feb 2024 11:15:21 AM CET" VOLUMIO_VERSION="3.629" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_DEVICENAME="Asus Tinkerboard" VOLUMIO_HASH="293a767fd1b71cabc5f5be4916f770a0"