-- Logs begin at Thu 2024-08-01 04:20:42 UTC, end at Sun 2024-08-18 12:13:42 UTC. -- Aug 18 12:12:22 volumio-bed volumio[830]: info: CoreCommandRouter::servicePushState Aug 18 12:12:22 volumio-bed volumio[830]: info: CoreStateMachine::pushState Aug 18 12:12:22 volumio-bed volumio[830]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 18 12:12:22 volumio-bed volumio[830]: info: CoreCommandRouter::volumioPushState Aug 18 12:12:22 volumio-bed volumio[830]: info: MRS: Pushing multiroomSync output update for this device Aug 18 12:12:22 volumio-bed volumio[830]: info: MRS: Pushing multiroomSync output Aug 18 12:12:30 volumio-bed vtcs[1379]: [2024-08-18 12:12:30.024] [tisoc] [warning] [PlaybackControllerImpl.cpp:278] Illegal state:5 Aug 18 12:13:41 volumio-bed volumio[1335]: [Vollibrespot] : Fetching autoplay context uri Aug 18 12:13:41 volumio-bed volumio[1335]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 141133739276178070141702983370469968764, audio_type: Track }, track_id: SpotifyId { id: 232996688060680354855649168232668579341, audio_type: Track } } Aug 18 12:13:41 volumio-bed volumio[1335]: [Vollibrespot] : No autoplay_uri found Aug 18 12:13:41 volumio-bed volumio[1335]: [Vollibrespot] : AutoplayError: MercuryError Aug 18 12:13:41 volumio-bed volumio[1335]: [Vollibrespot] : Loading with Spotify URI Aug 18 12:13:41 volumio-bed volumio[1335]: [Vollibrespot] : Fetching autoplay context uri Aug 18 12:13:41 volumio-bed volumio[1335]: [Vollibrespot] : (329360 ms) loaded Aug 18 12:13:41 volumio-bed volumio[1335]: [Vollibrespot] : No autoplay_uri found Aug 18 12:13:41 volumio-bed volumio[1335]: [Vollibrespot] : AutoplayError: MercuryError Aug 18 12:13:41 volumio-bed volumio[1335]: [Vollibrespot] : Loading with Spotify URI Aug 18 12:13:41 volumio-bed volumio[1335]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 232996688060680354855649168232668579341, audio_type: Track } } Aug 18 12:13:41 volumio-bed volumio[1335]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 232996688060680354855649168232668579341, audio_type: Track }, track_id: SpotifyId { id: 232996688060680354855649168232668579341, audio_type: Track } } Aug 18 12:13:41 volumio-bed volumio[830]: [SpotifyConnect] Marooned - 2011 Remaster Aug 18 12:13:41 volumio-bed volumio[830]: [SpotifyConnect] Device palyback is loading Aug 18 12:13:41 volumio-bed volumio[1335]: [Vollibrespot] : (329360 ms) loaded Aug 18 12:13:41 volumio-bed kernel: pcm512x 1-004c: No SCLK, using BCLK: -2 Aug 18 12:13:41 volumio-bed volumio[1335]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 232996688060680354855649168232668579341, audio_type: Track } } Aug 18 12:13:41 volumio-bed volumio[1335]: [Vollibrespot] : Event: SinkActive Aug 18 12:13:41 volumio-bed volumio[830]: [SpotifyConnect] Marooned - 2011 Remaster Aug 18 12:13:41 volumio-bed volumio[830]: [SpotifyConnect] Device palyback is loading Aug 18 12:13:41 volumio-bed volumio[830]: [SpotifyConnect] Sink acquired Aug 18 12:13:41 volumio-bed volumio[830]: info: Acquiring new spotify session Aug 18 12:13:41 volumio-bed volumio[830]: info: CoreCommandRouter::volumioStop Aug 18 12:13:41 volumio-bed volumio[830]: info: CoreStateMachine::stop Aug 18 12:13:41 volumio-bed volumio[830]: info: CoreStateMachine::serviceStop Aug 18 12:13:41 volumio-bed volumio[830]: info: CoreCommandRouter::serviceStop Aug 18 12:13:41 volumio-bed volumio[830]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 18 12:13:41 volumio-bed volumio[830]: TypeError: Cannot read property 'then' of undefined Aug 18 12:13:41 volumio-bed volumio[830]: at SpotConnEvents. (/data/plugins/music_service/spop/index.js:2156:31) Aug 18 12:13:41 volumio-bed volumio[830]: at SpotConnEvents.emit (events.js:315:20) Aug 18 12:13:41 volumio-bed volumio[830]: at SpotConnEvents.parseData (/data/plugins/music_service/spop/SpotConnController.js:100:16) Aug 18 12:13:41 volumio-bed volumio[830]: at Socket. (/data/plugins/music_service/spop/SpotConnController.js:15:12) Aug 18 12:13:41 volumio-bed volumio[830]: at Socket.emit (events.js:315:20) Aug 18 12:13:41 volumio-bed volumio[830]: at UDP.onMessage [as onmessage] (dgram.js:919:8) Aug 18 12:13:41 volumio-bed volumio[830]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 18 12:13:42 volumio-bed vtcs[1379]: [2024-08-18 12:13:42] [info] asio async_shutdown error: asio.misc:2 (End of file) Aug 18 12:13:42 volumio-bed sudo[588]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-18 12:12 Aug 18 12:13:42 volumio-bed sudo[588]: 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="b1c3cf61c2a0027c66bab1eb0a3795f80c1f2e95" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="e3eb3ee5b16063502f3a1735e11a28cfe54a0f46" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sun 25 Jun 2023 07:20:58 PM CEST" VOLUMIO_VERSION="3.512" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="946a42b62509f37b5e7e2d86a2932a62"