-- Logs begin at Wed 2023-05-17 17:18:23 UTC, end at Tue 2024-04-23 22:02:41 UTC. -- Apr 23 22:02:27 minidsp-shd volumio[1714]: [Vollibrespot] : error sending packet Os { code: 1, kind: PermissionDenied, message: "Operation not permitted" } Apr 23 22:02:39 minidsp-shd volumio[1714]: [Vollibrespot] : Connecting to AP "ap-gew1.spotify.com:443" Apr 23 22:02:40 minidsp-shd volumio[1714]: [Vollibrespot] : Authenticated as "a1sdf4qv7olvx652ns4xz8j0p" ! Apr 23 22:02:40 minidsp-shd volumio[1714]: [Vollibrespot] : Using alsa sink Apr 23 22:02:40 minidsp-shd volumio[1714]: [Vollibrespot] : Metadata pipe established Apr 23 22:02:40 minidsp-shd volumio[1714]: [Vollibrespot] : Country: "GB" Apr 23 22:02:40 minidsp-shd volumio[1714]: [Vollibrespot] : Event: Volume { volume_to_mixer: 21626 } Apr 23 22:02:40 minidsp-shd volumio[744]: [SpotifyConnect] Volume Spotify: 32.99916075379568 Volumio: 33 Apr 23 22:02:40 minidsp-shd volumio[1714]: [Vollibrespot] : Event: SessionActive { became_active_at: 1713909759307 } Apr 23 22:02:40 minidsp-shd volumio[1714]: [Vollibrespot] : SessionActive! Apr 23 22:02:40 minidsp-shd volumio[1714]: [Vollibrespot] : Fetching autoplay context uri Apr 23 22:02:40 minidsp-shd volumio[744]: [SpotifyConnect] A connect session has begun Apr 23 22:02:40 minidsp-shd volumio[744]: info: Acquiring new spotify session Apr 23 22:02:40 minidsp-shd volumio[744]: info: CoreCommandRouter::volumioStop Apr 23 22:02:40 minidsp-shd volumio[1714]: [Vollibrespot] : Event: PlaybackStarted { track_id: SpotifyId { id: 166429427381791888506933237899671859568, audio_type: Track } } Apr 23 22:02:40 minidsp-shd volumio[1714]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 166429427381791888506933237899671859568, audio_type: Track } } Apr 23 22:02:40 minidsp-shd volumio[744]: info: CoreStateMachine::stop Apr 23 22:02:40 minidsp-shd volumio[744]: info: CoreStateMachine::serviceStop Apr 23 22:02:40 minidsp-shd volumio[744]: info: CoreCommandRouter::serviceStop Apr 23 22:02:40 minidsp-shd volumio[744]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 23 22:02:40 minidsp-shd volumio[744]: TypeError: Cannot read property 'then' of undefined Apr 23 22:02:40 minidsp-shd volumio[744]: at SpotConnEvents.SpotConn.on (/data/plugins/music_service/spop/index.js:2095:31) Apr 23 22:02:40 minidsp-shd volumio[744]: at emitNone (events.js:106:13) Apr 23 22:02:40 minidsp-shd volumio[744]: at SpotConnEvents.emit (events.js:208:7) Apr 23 22:02:40 minidsp-shd volumio[744]: at SpotConnEvents.parseData (/data/plugins/music_service/spop/SpotConnController.js:81:16) Apr 23 22:02:40 minidsp-shd volumio[744]: at Socket.SpotConnEvents._udpsource.on.msg (/data/plugins/music_service/spop/SpotConnController.js:15:12) Apr 23 22:02:40 minidsp-shd volumio[744]: at emitTwo (events.js:126:13) Apr 23 22:02:40 minidsp-shd volumio[744]: at Socket.emit (events.js:214:7) Apr 23 22:02:40 minidsp-shd volumio[744]: at UDP.onMessage [as onmessage] (dgram.js:659:8) Apr 23 22:02:40 minidsp-shd volumio[1714]: [Vollibrespot] : Autoplay uri resolved to <"spotify:station:playlist:4VCcqjOfYEJFv5rR2xZW1V"> Apr 23 22:02:40 minidsp-shd volumio[1714]: [Vollibrespot] : Resolving uri "spotify:station:playlist:4VCcqjOfYEJFv5rR2xZW1V" Apr 23 22:02:40 minidsp-shd volumio[744]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 23 22:02:40 minidsp-shd vtcs[968]: [2024-04-23 22:02:40] [info] asio async_shutdown error: asio.misc:2 (End of file) Apr 23 22:02:40 minidsp-shd volumio[1714]: [Vollibrespot] : Event: GotToken { token: Token { access_token: "BQDyJ6lZzDWpKtr4sAwdCl30zO6-RkaV1v2qmM3GsjFd5bEHQmGdD4AATqFhqur-byhg2AlDymHtCOCIMN0A012gk1cr4Sr291q2MyboCoxVxmQaxIDUE5WdMyOlZ-gVx3Ff8Jmwll4bJsJxTiMNQGgstZpfKkQSBGEgnNnVIzPTTkTHoSruUqGGOre8x7dOoXVLexSyw_Y1xj-uxEUfd_u4_w", expires_in: 3600, token_type: "Bearer", scope: ["streaming", "user-read-playback-state", "user-modify-playback-state", "user-read-currently-playing", "user-read-private"] } } Apr 23 22:02:40 minidsp-shd volumio[1714]: [Vollibrespot] : Loading with Spotify URI Apr 23 22:02:40 minidsp-shd volumio[1714]: [Vollibrespot] : (354120 ms) loaded Apr 23 22:02:40 minidsp-shd volumio[1714]: [Vollibrespot] : Event: SinkActive Apr 23 22:02:40 minidsp-shd volumio[1714]: [Vollibrespot] : Resolved 50 tracks from <"spotify:playlist:4VCcqjOfYEJFv5rR2xZW1V"> Apr 23 22:02:41 minidsp-shd sudo[3966]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-23 22:01 Apr 23 22:02:41 minidsp-shd sudo[3966]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 8 (jessie)" NAME="Debian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=debian HOME_URL="http://www.debian.org/" SUPPORT_URL="http://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="6d9442f761618503268d0366db805a252514dec1" VOLUMIO_BE_VERSION="3e944f35d34b575025d16b976b4cb4d9aed53b66" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="minidspshd" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Jul 26 13:26:42 CEST 2022" VOLUMIO_VERSION="1.125" VOLUMIO_HARDWARE="nanopineo2" VOLUMIO_VENDOR="miniDSP" VOLUMIO_MODEL="SHD" VOLUMIO_VENDOR_MODEL="miniDSP SHD" VOLUMIO_HASH="9c17c7c45298f5ca9f5ec80e116295fd"