-- Logs begin at Mon 2024-08-19 07:42:52 UTC, end at Thu 2024-08-22 17:04:13 UTC. -- Aug 22 17:03:16 volumio_la-palat-5g volumio[22024]: info: Pushing metadata Aug 22 17:03:16 volumio_la-palat-5g volumio[22024]: info: CoreCommandRouter::servicePushState Aug 22 17:03:16 volumio_la-palat-5g volumio[22024]: info: CoreStateMachine::pushState Aug 22 17:03:16 volumio_la-palat-5g volumio[22024]: info: CoreCommandRouter::volumioPushState Aug 22 17:03:16 volumio_la-palat-5g volumio[22024]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 22 17:03:17 volumio_la-palat-5g volumio[22024]: info: CoreCommandRouter::servicePushState Aug 22 17:03:17 volumio_la-palat-5g volumio[22024]: info: CoreStateMachine::pushState Aug 22 17:03:17 volumio_la-palat-5g volumio[22024]: info: CoreCommandRouter::volumioPushState Aug 22 17:03:17 volumio_la-palat-5g volumio[22024]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 22 17:03:33 volumio_la-palat-5g volumio[22024]: info: Pushing metadata Aug 22 17:03:33 volumio_la-palat-5g volumio[22024]: info: CoreCommandRouter::servicePushState Aug 22 17:03:33 volumio_la-palat-5g volumio[22024]: info: CoreStateMachine::pushState Aug 22 17:03:33 volumio_la-palat-5g volumio[22024]: info: CoreCommandRouter::volumioPushState Aug 22 17:03:33 volumio_la-palat-5g volumio[22024]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 22 17:03:33 volumio_la-palat-5g volumio[22024]: info: CoreCommandRouter::servicePushState Aug 22 17:03:33 volumio_la-palat-5g volumio[22024]: info: CoreStateMachine::pushState Aug 22 17:03:33 volumio_la-palat-5g volumio[22024]: info: CoreCommandRouter::volumioPushState Aug 22 17:03:33 volumio_la-palat-5g volumio[22024]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 22 17:03:34 volumio_la-palat-5g volumio[22024]: info: CoreCommandRouter::servicePushState Aug 22 17:03:34 volumio_la-palat-5g volumio[22024]: info: CoreStateMachine::pushState Aug 22 17:03:34 volumio_la-palat-5g volumio[22024]: info: CoreCommandRouter::volumioPushState Aug 22 17:03:34 volumio_la-palat-5g volumio[22024]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 22 17:03:41 volumio_la-palat-5g volumio[22024]: info: CoreCommandRouter::servicePushState Aug 22 17:03:41 volumio_la-palat-5g volumio[22024]: info: CoreStateMachine::pushState Aug 22 17:03:41 volumio_la-palat-5g volumio[22024]: info: CoreCommandRouter::volumioPushState Aug 22 17:03:41 volumio_la-palat-5g volumio[22024]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 22 17:03:54 volumio_la-palat-5g vtcs[22387]: [2024-08-22 17:03:54] [error] handle_read_frame error: asio.system:104 (Connection reset by peer) Aug 22 17:03:54 volumio_la-palat-5g vtcs[22387]: [2024-08-22 17:03:54] [info] asio async_shutdown error: asio.system:32 (Broken pipe) Aug 22 17:04:12 volumio_la-palat-5g volumio[1083]: [Vollibrespot] : Connecting to AP "ap-gew1.spotify.com:443" Aug 22 17:04:12 volumio_la-palat-5g volumio[1083]: [Vollibrespot] : Authenticated as "lopdz1yflst1ftmn34al49zbj" ! Aug 22 17:04:12 volumio_la-palat-5g volumio[1083]: [Vollibrespot] : Metadata pipe established Aug 22 17:04:12 volumio_la-palat-5g volumio[1083]: [Vollibrespot] : Country: "RO" Aug 22 17:04:12 volumio_la-palat-5g volumio[1083]: [Vollibrespot] : Event: Volume { volume_to_mixer: 21626 } Aug 22 17:04:12 volumio_la-palat-5g volumio[1083]: [Vollibrespot] : Using alsa sink Aug 22 17:04:12 volumio_la-palat-5g volumio[22024]: [SpotifyConnect] Volume Spotify: 32.99916075379568 Volumio: 33 Aug 22 17:04:13 volumio_la-palat-5g volumio[1083]: [Vollibrespot] : Fetching autoplay context uri Aug 22 17:04:13 volumio_la-palat-5g volumio[1083]: [Vollibrespot] : Event: SessionActive { became_active_at: 1724346253295 } Aug 22 17:04:13 volumio_la-palat-5g volumio[1083]: [Vollibrespot] : SessionActive! Aug 22 17:04:13 volumio_la-palat-5g volumio[1083]: [Vollibrespot] : Event: PlaybackStarted { track_id: SpotifyId { id: 200234509964306392557055772128211044150, audio_type: Track } } Aug 22 17:04:13 volumio_la-palat-5g volumio[1083]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 200234509964306392557055772128211044150, audio_type: Track } } Aug 22 17:04:13 volumio_la-palat-5g volumio[22024]: [SpotifyConnect] A connect session has begun Aug 22 17:04:13 volumio_la-palat-5g volumio[22024]: info: Acquiring new spotify session Aug 22 17:04:13 volumio_la-palat-5g volumio[22024]: info: CoreCommandRouter::volumioStop Aug 22 17:04:13 volumio_la-palat-5g volumio[22024]: info: CoreStateMachine::stop Aug 22 17:04:13 volumio_la-palat-5g volumio[22024]: info: CoreStateMachine::serviceStop Aug 22 17:04:13 volumio_la-palat-5g volumio[22024]: info: CoreCommandRouter::serviceStop Aug 22 17:04:13 volumio_la-palat-5g volumio[22024]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 22 17:04:13 volumio_la-palat-5g volumio[22024]: TypeError: Cannot read property 'then' of undefined Aug 22 17:04:13 volumio_la-palat-5g volumio[22024]: at SpotConnEvents.SpotConn.on (/data/plugins/music_service/spop/index.js:2095:31) Aug 22 17:04:13 volumio_la-palat-5g volumio[22024]: at emitNone (events.js:106:13) Aug 22 17:04:13 volumio_la-palat-5g volumio[22024]: at SpotConnEvents.emit (events.js:208:7) Aug 22 17:04:13 volumio_la-palat-5g volumio[22024]: at SpotConnEvents.parseData (/data/plugins/music_service/spop/SpotConnController.js:81:16) Aug 22 17:04:13 volumio_la-palat-5g volumio[22024]: at Socket.SpotConnEvents._udpsource.on.msg (/data/plugins/music_service/spop/SpotConnController.js:15:12) Aug 22 17:04:13 volumio_la-palat-5g volumio[22024]: at emitTwo (events.js:126:13) Aug 22 17:04:13 volumio_la-palat-5g volumio[22024]: at Socket.emit (events.js:214:7) Aug 22 17:04:13 volumio_la-palat-5g volumio[22024]: at UDP.onMessage [as onmessage] (dgram.js:659:8) Aug 22 17:04:13 volumio_la-palat-5g volumio[22024]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 22 17:04:13 volumio_la-palat-5g vtcs[22387]: [2024-08-22 17:04:13.327] [tisoc] [warning] [PlaybackControllerImpl.cpp:472] Illegal state:0 Aug 22 17:04:13 volumio_la-palat-5g volumio[1083]: [Vollibrespot] : Autoplay uri resolved to <"spotify:station:playlist:6IKQrtMc4c00YzONcUt7QH"> Aug 22 17:04:13 volumio_la-palat-5g volumio[1083]: [Vollibrespot] : Resolving uri "spotify:station:playlist:6IKQrtMc4c00YzONcUt7QH" Aug 22 17:04:13 volumio_la-palat-5g volumio[1083]: [Vollibrespot] : Loading with Spotify URI Aug 22 17:04:13 volumio_la-palat-5g volumio[1083]: [Vollibrespot] : Event: GotToken { token: Token { access_token: "BQBQrBoM0gfB_b8ng-UHAHKp63EZFJhdRA32ks8pRC4bKTGU52H57XSADpslGivwzHtGfQ_J5YWfLm4FElbFUbrIIA5hO4CRT0sOvFT6E1Vd0ge6HqFqtrDiv-I6i3_a5ZcRTVWLul-pFHeSMqt9uCj3UavFuxc1-T_WNqah-BZeg_N-QWksjwxhvOsJXar0ssLjPRpOk9SYHtXVm9gS2A62ap_40y0EFg", expires_in: 3600, token_type: "Bearer", scope: ["streaming", "user-read-playback-state", "user-modify-playback-state", "user-read-currently-playing", "user-read-private"] } } Aug 22 17:04:13 volumio_la-palat-5g sudo[28178]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-22 17:03 Aug 22 17:04:13 volumio_la-palat-5g sudo[28178]: pam_unix(sudo:session): session opened for user root by (uid=0) 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="958dedc0edff114f244fa9acc70cf53089d9e5ae" VOLUMIO_BE_VERSION="fd3e37ffec12db034ec93f94dbc26e5495d5b91a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Oct 6 12:34:49 CEST 2021" VOLUMIO_VERSION="2.917" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="98c8f08c531a6f0456f17ab17795e35f"