-- Logs begin at Fri 2024-04-12 15:24:47 UTC, end at Sat 2024-04-13 09:22:22 UTC. -- Apr 13 09:21:51 musical-fidelity-demo volumio[1032]: info: CoreCommandRouter::servicePushState Apr 13 09:21:51 musical-fidelity-demo volumio[1032]: info: CoreStateMachine::pushState Apr 13 09:21:51 musical-fidelity-demo volumio[1032]: info: CoreCommandRouter::volumioPushState Apr 13 09:21:51 musical-fidelity-demo volumio[1032]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 13 09:22:20 musical-fidelity-demo volumio[1417]: [Vollibrespot] : Connecting to AP "ap-gew1.spotify.com:443" Apr 13 09:22:21 musical-fidelity-demo volumio[1417]: [Vollibrespot] : Authenticated as "garchontis" ! Apr 13 09:22:21 musical-fidelity-demo volumio[1417]: [Vollibrespot] : Using alsa sink Apr 13 09:22:21 musical-fidelity-demo volumio[1417]: [Vollibrespot] : Metadata pipe established Apr 13 09:22:21 musical-fidelity-demo volumio[1417]: [Vollibrespot] : Event: Volume { volume_to_mixer: 65535 } Apr 13 09:22:21 musical-fidelity-demo volumio[1417]: [Vollibrespot] : Country: "CY" Apr 13 09:22:21 musical-fidelity-demo volumio[1032]: [SpotifyConnect] Volume Spotify: 100 Volumio: 100 Apr 13 09:22:21 musical-fidelity-demo volumio[1417]: [Vollibrespot] : Event: SessionActive { became_active_at: 1713000141794 } Apr 13 09:22:21 musical-fidelity-demo volumio[1417]: [Vollibrespot] : SessionActive! Apr 13 09:22:21 musical-fidelity-demo volumio[1417]: [Vollibrespot] : Fetching autoplay context uri Apr 13 09:22:21 musical-fidelity-demo volumio[1032]: [SpotifyConnect] A connect session has begun Apr 13 09:22:21 musical-fidelity-demo volumio[1032]: info: Acquiring new spotify session Apr 13 09:22:21 musical-fidelity-demo volumio[1417]: [Vollibrespot] : Event: PlaybackStarted { track_id: SpotifyId { id: 231711822690741733954323227679107558079, audio_type: Track } } Apr 13 09:22:21 musical-fidelity-demo volumio[1417]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 231711822690741733954323227679107558079, audio_type: Track } } Apr 13 09:22:21 musical-fidelity-demo volumio[1032]: info: CoreCommandRouter::volumioStop Apr 13 09:22:21 musical-fidelity-demo volumio[1032]: info: CoreStateMachine::stop Apr 13 09:22:21 musical-fidelity-demo volumio[1032]: info: CoreStateMachine::serviceStop Apr 13 09:22:21 musical-fidelity-demo volumio[1032]: info: CoreCommandRouter::serviceStop Apr 13 09:22:21 musical-fidelity-demo volumio[1032]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 13 09:22:21 musical-fidelity-demo volumio[1032]: TypeError: Cannot read property 'then' of undefined Apr 13 09:22:21 musical-fidelity-demo volumio[1032]: at SpotConnEvents.SpotConn.on (/volumio/app/plugins/music_service/spop/index.js:2073:31) Apr 13 09:22:21 musical-fidelity-demo volumio[1032]: at emitNone (events.js:106:13) Apr 13 09:22:21 musical-fidelity-demo volumio[1032]: at SpotConnEvents.emit (events.js:208:7) Apr 13 09:22:21 musical-fidelity-demo volumio[1032]: at SpotConnEvents.parseData (/volumio/app/plugins/music_service/spop/SpotConnController.js:81:16) Apr 13 09:22:21 musical-fidelity-demo volumio[1032]: at Socket.SpotConnEvents._udpsource.on.msg (/volumio/app/plugins/music_service/spop/SpotConnController.js:15:12) Apr 13 09:22:21 musical-fidelity-demo volumio[1032]: at emitTwo (events.js:126:13) Apr 13 09:22:21 musical-fidelity-demo volumio[1032]: at Socket.emit (events.js:214:7) Apr 13 09:22:21 musical-fidelity-demo volumio[1032]: at UDP.onMessage [as onmessage] (dgram.js:659:8) Apr 13 09:22:21 musical-fidelity-demo volumio[1032]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 13 09:22:21 musical-fidelity-demo vtcs[1418]: [2024-04-13 09:22:21.814] [tisoc] [warning] [PlaybackControllerImpl.cpp:472] Illegal state:0 Apr 13 09:22:21 musical-fidelity-demo volumio[1417]: [Vollibrespot] : Autoplay uri resolved to <"spotify:station:album:727EIB96mioWGAGyTYnO5p"> Apr 13 09:22:21 musical-fidelity-demo volumio[1417]: [Vollibrespot] : Resolving uri "spotify:station:album:727EIB96mioWGAGyTYnO5p" Apr 13 09:22:21 musical-fidelity-demo vtcs[1418]: [2024-04-13 09:22:21] [info] asio async_shutdown error: asio.misc:2 (End of file) Apr 13 09:22:21 musical-fidelity-demo volumio[1417]: [Vollibrespot] : Event: GotToken { token: Token { access_token: "BQCSpPkySilN32sR-OMSRZTCMxg7OqgM4kR4daYO6kwK4s9JQG6F7seM8pW8h4n5aTHup5pAXF09C-60X2fdKNXS9dvH8k0luqiWhPN5i4wPkMd_Rir9d1mc-19KnyHtJjc1A5cwuyw4W-aJt8LknfbAbmhVC5ck-ZARWuazCY0Jf9LIsLZ2x4VwFsscp612wiVh9w", 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 09:22:22 musical-fidelity-demo volumio[1417]: [Vollibrespot] : Loading with Spotify URI Apr 13 09:22:22 musical-fidelity-demo volumio[1417]: [Vollibrespot] : Reducing normalisation factor to prevent clipping. Please add negative pregain to avoid. Apr 13 09:22:22 musical-fidelity-demo sudo[24044]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-13 09:21 Apr 13 09:22:22 musical-fidelity-demo sudo[24044]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 09:22:22 musical-fidelity-demo volumio[1417]: [Vollibrespot] : Unable to parse JSONContext Error("invalid type: map, expected a string", line: 0, column: 0) Apr 13 09:22:22 musical-fidelity-demo volumio[1417]: Object({"correlation_id": String("ssp~55266c75f97711eea8065a71bff34f25"), "imageUri": String("spotify:image:ab67616d0000b273981a214003f49daa8b32ed52"), "next_page_url": String("hm://radio-apollo/v3/tracks/spotify:station:album:727EIB96mioWGAGyTYnO5p?salt=509125&autoplay=true&count=50&isVideo=false&prev_tracks=3cfVcO6vPwkPKYUzd5z8SI,3Wi3qEBzLyuiKzSBGBQSaf,5j4EfZ8UEK90lJ7DRHGYfR,4sfYQveGXUELiGaVWJELxZ,0rd0rUHVQFaRsWZ34WR7Zx,7ExwOPh8AzzyHcZNiP61KV,4LHZgCwBorIgqFGIEBcBYo,5GnJurSBoj6oCoCWHkAkzB,1ISb9q0oFPkBj8a7aWBXTe,1pPvhBme8aj5nbjEAkHUQ6,1IF5Brdc9EKhZjb2UOKlTA,47xZ59XjNaGgnmWy2X1WUL,4h7WMftQEVwnZIFJfyfq5R,374CxrzDRS58Z06PRo0Sf2,4SeVAtu35e2m5v0nVaxDPf,37z0cJcd4Mwm3wClrCxxa8,1PnTxsxWwWmJyCKQU0u5jL,4OmZySeeOJYidGt8thVxLV,6T1XEsotnNLOBFYFyTiEwc,5knmhbxOpdAa9VqwoRSCln,4RzzO0a1orFvfEHTz78xFc,0D6keoBLIRqiqWZSBslp76,1nZrxUf8tfotQj7kltiIyQ,0EUjBe6SrQkuGqH58o15x9,17IGCxI5AVjVk8OIWh0hgl,5yAtjSo3xYrLtiKfbZZox8,66OfFALuz5m0OstynWGEDz,4XSwn2DpUy5ZbzcCmrBSqN,1QkWsJmmoIv2Qa7HO800lu,5sEkWOyk1yWcQlZ0azobZK,7boiW9vDdRdXsppfDHuXX6,7AIdHsUnuW7PMFK4lwZeXu,19vO5SGuRIzG8bHpgnTHee,2QpjxfugzwgZ4ruMT1oZAN,2CFXSziSrD8HF2Yyy6h8wi,2i27ceQ2RkcoXtF1mfSkSx,4dvqXFGAhxAdNSU5PTQT6l,3YH2vH1tciClL2fGJK6iLI,5B1KbUoek5vU5WaKT5z4Gp,6oXjJinC9IsQfPMXyrEdrF,5cr3Nss9uamtSe1dtoqIhG,305Y7X28x5BOUkvSe0VUj1,16sAKVka6IqZIHYkpA2aIr,6MxglevrE87oPkNok6wGTC,2vz2m347gPBEt4hsfKGN5T,6LkfxwQWOxY9cfQc1Zn8GJ,5vmCpWoTd12LYW1HJJ5qpP,7A5DxjkmTHLdYBx5nbEf2m,0JpEBe6wg0mADqKoNcL8d0,5Gg7h21SfWFf7BGt16qah0"), "related_artists": Array([Object({"artistName": String("Masako Deguci"), "artistUri": String("spotify:artist:3JOxfVBCaU9r36FZXxsI7J"), "imageUri": String("")}), Object({"artistName": String("Giovanna Casolla"), "artistUri": String("spotify:artist:684NMLmtAYzEwsOYPv5Fiu"), "imageUri": String("")}), Object({"artistName": String("Lando Bartolini"), "artistUri": String("spotify:artist:70lTUoYokSBEIwL8NzyDA7"), "imageUri": String("")}), Object({"artistName": String("Gorka Gerrikabeitia"), "artistUri": String("spotify:artist:67PTdJHhofnKRHVKRZdI0O"), "imageUri": String("")}), Object( Apr 13 09:22:22 musical-fidelity-demo volumio[1417]: {"artistName": String("Francisco Heredia"), "artistUri": String("spotify:artist:6iAcC9n9Ju4YCpNdxOkCWI"), "imageUri": String("")})]), "seeds": Array([String("spotify:album:727EIB96mioWGAGyTYnO5p")]), "subtitle": String("Giacomo Puccini"), "subtitleUri": String("spotify:artist:0OzxPXyowUEQ532c9AmHUR"), "subtitles": Array([Object({"name": String("Giacomo Puccini"), "uri": String("spotify:artist:0OzxPXyowUEQ532c9AmHUR")})]), "title": String("Puccini: Turandot"), "titleUri": String("spotify:album:727EIB96mioWGAGyTYnO5p"), "tracks": Array([Object({"album_uri": String("spotify:album:727EIB96mioWGAGyTYnO5p"), "artistUri": String("spotify:artist:0OzxPXyowUEQ532c9AmHUR"), "artist_uri": String("spotify:artist:0OzxPXyowUEQ532c9AmHUR"), "metadata": Object({"album_title": String("Puccini: Turandot"), "artist_name": String("Giacomo Puccini"), "artist_name:1": String("Masako Deguci"), "artist_name:10": String("Felipe Bou"), "artist_name:11": String("Bilbao Choral Society"), "artist_name:12": String("Malaga Philharmonic Orchestra"), "artist_name:13": String("Alexander Rahbari"), "artist_name:2": String("Giovanna Casolla"), "artist_name:3": String("Lando Bartolini"), "artist_name:4": String("Gorka Gerrikabeitia"), "artist_name:5": String("Francisco Heredia"), "artist_name:6": String("Javier Mas"), "artist_name:7": String("Vicenc Esteve"), "artist_name:8": String("Armando Ariostini"), "artist_name:9": String("Jose Garcia-Quijada"), "artist_uri": String("spotify:artist:0OzxPXyowUEQ532c9AmHUR"), "artist_uri:1": String("spotify:artist:3JOxfVBCaU9r36FZXxsI7J"), "artist_uri:10": String("spotify:artist:49czxWXDqGf7omhCO6vFOV"), "artist_uri:11": String("spotify:artist:1gFEvfFPs6PGszN1i3LPCZ"), "artist_uri:12": String("spotify:artist:0z2s5RHZgYiWAaQRF6Rd3Z"), "artist_uri:13": String("spotify:artist:2enPrYI2oHc6IWCuCmLfX9"), "artist_uri:2": String("spotify:artist:684NMLmtAYzEwsOYPv5Fiu"), "artist_uri:3": String("spotify:artist:70lTUoYokSBEIwL8NzyDA7"), "artist_uri:4": String("spotify:artist:67PTdJHhofnKRHVKRZdI0O"), "artist_uri:5": String( Apr 13 09:22:22 musical-fidelity-demo volumio[1417]: "spotify:artist:6iAcC9n9Ju4YCpNdxOkCWI"), "artist_uri:6": String("spotify:artist:6mGeJS0WZxcQmU9mJT1Uj5"), "artist_uri:7": String("spotify:artist:2nFpd0IdNkJb8q7Is3Wp42"), "artist_uri:8": String("spotify:artist:7dBzGSN6H0m9be5RUGjAoS"), "artist_uri:9": String("spotify:artist:6HO3iiZNkNm8uX4HD7lM8X"), "decision_id": String("ssp~55266c75f97711eea8065a71bff34f25"), "image_url": String("spotify:image:ab67616d0000b273981a214003f49daa8b32ed52"), "is_explicit": String("false"), "is_promotional": String("false"), "title": String("Turandot: Act I: Là, sui monti dell'est")}), "name": String("Turandot: Act I: Là, sui monti dell'est"), "original_gid": String("3cfVcO6vPwkPKYUzd5z8SI"), "uid": String("f6b6ca08e57b3213"), "uri": String("spotify:track:3cfVcO6vPwkPKYUzd5z8SI")}), Object({"album_uri": String("spotify:album:0gdvGG1uXfbGqudXlJEcw0"), "artistUri": String("spotify:artist:2jCGEMSZXMSOImpD8sqo56"), "artist_uri": String("spotify:artist:2jCGEMSZXMSOImpD8sqo56"), "metadata": Object({"album_title": String("Donizetti: Linda di Chamounix, A. 62"), "artist_name": String("Gaetano Donizetti"), "artist_name:1": String("Jessica Pratt"), "artist_name:2": String("Teresa Iervolino"), "artist_name:3": String("Coro del Maggio Musicale Fiorentino"), "artist_name:4": String("Orchestra del Maggio Musicale Fiorentino"), "artist_name:5": String("Michele Gamba"), "artist_uri": String("spotify:artist:2jCGEMSZXMSOImpD8sqo56"), "artist_uri:1": String("spotify:artist:5axz31mltRMW9F82oAqS5g"), "artist_uri:2": String("spotify:artist:7Fo9nvZqxkiNJp9KY7aTsB"), "artist_uri:3": String("spotify:artist:05k3pjtqfItF6iNAq8NXTk"), "artist_uri:4": String("spotify:artist:0YfVUjHFL0QWCSFRzliCDk"), "artist_uri:5": String("spotify:artist:70AYzNbYur0IQh7zY2Mdb9"), "decision_id": String("ssp~55266c75f97711eea8065a71bff34f25"), "image_url": String("spotify:image:ab67616d0000b273675a91d1b42816257e5bad59"), "is_explicit": String("false"), "is_promotional": String("false"), "title": String("Linda di Chamounix, A. 62, Act I Scene 3: Qui sì, pria della partenza")}), PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" 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="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="6d9442f761618503268d0366db805a252514dec1" VOLUMIO_BE_VERSION="3e944f35d34b575025d16b976b4cb4d9aed53b66" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="mf" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Sep 6 12:01:32 CEST 2022" VOLUMIO_VERSION="1.081" VOLUMIO_HARDWARE="pi" VOLUMIO_VENDOR="Musical Fidelity" VOLUMIO_MODEL="MX-Stream" VOLUMIO_VENDOR_MODEL="Musical Fidelity MX-Stream" VOLUMIO_HASH="fcc4f08af4feb5d7558230fb59bf1e14"