-- Logs begin at Tue 2025-12-23 00:22:02 MST, end at Wed 2025-12-24 20:01:30 MST. -- Dec 24 20:00:03 volumio volumio[1147]: info: CoreCommandRouter::volumioGetState Dec 24 20:00:03 volumio volumio[1147]: info: Listing playlists Dec 24 20:00:03 volumio volumio[1147]: info: Listing playlists Dec 24 20:00:13 volumio volumio[1147]: info: CoreCommandRouter::volumioGetState Dec 24 20:00:23 volumio volumio[1147]: info: CoreCommandRouter::volumioGetState Dec 24 20:00:23 volumio volumio[1147]: info: Listing playlists Dec 24 20:00:23 volumio volumio[1147]: info: Listing playlists Dec 24 20:00:33 volumio volumio[1147]: info: CoreCommandRouter::volumioGetState Dec 24 20:00:43 volumio volumio[1147]: info: CoreCommandRouter::volumioGetState Dec 24 20:00:43 volumio volumio[1147]: info: Listing playlists Dec 24 20:00:43 volumio volumio[1147]: info: Listing playlists Dec 24 20:00:53 volumio volumio[1147]: info: CoreCommandRouter::volumioGetState Dec 24 20:01:03 volumio volumio[1147]: info: CoreCommandRouter::volumioGetState Dec 24 20:01:03 volumio volumio[1147]: info: Listing playlists Dec 24 20:01:03 volumio volumio[1147]: info: Listing playlists Dec 24 20:01:13 volumio volumio[1147]: info: CoreCommandRouter::volumioGetState Dec 24 20:01:23 volumio volumio[1147]: info: CoreCommandRouter::volumioGetState Dec 24 20:01:23 volumio volumio[1147]: info: Listing playlists Dec 24 20:01:23 volumio volumio[1147]: info: Listing playlists Dec 24 20:01:29 volumio volumio[1507]: [Vollibrespot] : Connecting to AP "ap-guc3.spotify.com:443" Dec 24 20:01:29 volumio volumio[1507]: [Vollibrespot] : Authenticated as "deckt007" ! Dec 24 20:01:29 volumio volumio[1507]: [Vollibrespot] : Using alsa sink Dec 24 20:01:29 volumio volumio[1507]: [Vollibrespot] : Metadata pipe established Dec 24 20:01:29 volumio volumio[1507]: [Vollibrespot] : Country: "CA" Dec 24 20:01:29 volumio volumio[1507]: [Vollibrespot] : Event: Volume { volume_to_mixer: 65535 } Dec 24 20:01:29 volumio volumio[1147]: [SpotifyConnect] Volume Spotify: 100 Volumio: 100 Dec 24 20:01:30 volumio volumio[1507]: [Vollibrespot] : Event: SessionActive { became_active_at: 1766631690099 } Dec 24 20:01:30 volumio volumio[1507]: [Vollibrespot] : SessionActive! Dec 24 20:01:30 volumio volumio[1507]: [Vollibrespot] : Fetching autoplay context uri Dec 24 20:01:30 volumio volumio[1507]: [Vollibrespot] : Event: PlaybackStarted { track_id: SpotifyId { id: 199762824247604491839712516242671561672, audio_type: Track } } Dec 24 20:01:30 volumio volumio[1507]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 199762824247604491839712516242671561672, audio_type: Track } } Dec 24 20:01:30 volumio volumio[1147]: [SpotifyConnect] A connect session has begun Dec 24 20:01:30 volumio volumio[1147]: [SpotifyConnect] Device palyback is active! Dec 24 20:01:30 volumio volumio[1147]: info: CoreCommandRouter::volumioGetState Dec 24 20:01:30 volumio volumio[1147]: [SpotifyConnect] Currently active: tidalconnect Dec 24 20:01:30 volumio volumio[1147]: info: CoreCommandRouter::volumioStop Dec 24 20:01:30 volumio volumio[1147]: info: CoreStateMachine::stop Dec 24 20:01:30 volumio volumio[1147]: info: CoreStateMachine::serviceStop Dec 24 20:01:30 volumio volumio[1147]: info: CoreCommandRouter::serviceStop Dec 24 20:01:30 volumio vtcs[1545]: [2025-12-24 20:01:30.104] [tisoc] [warning] [PlaybackControllerImpl.cpp:510] Illegal state:1 Dec 24 20:01:30 volumio volumio[1147]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 24 20:01:30 volumio volumio[1147]: TypeError: Cannot read property 'then' of undefined Dec 24 20:01:30 volumio volumio[1147]: at SpotConnEvents. (/data/plugins/music_service/spop/index.js:2034:27) Dec 24 20:01:30 volumio volumio[1147]: at SpotConnEvents.emit (events.js:315:20) Dec 24 20:01:30 volumio volumio[1147]: at SpotConnEvents.parseData (/data/plugins/music_service/spop/SpotConnController.js:73:16) Dec 24 20:01:30 volumio volumio[1147]: at Socket. (/data/plugins/music_service/spop/SpotConnController.js:15:12) Dec 24 20:01:30 volumio volumio[1147]: at Socket.emit (events.js:315:20) Dec 24 20:01:30 volumio volumio[1147]: at UDP.onMessage [as onmessage] (dgram.js:919:8) Dec 24 20:01:30 volumio volumio[1147]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 24 20:01:30 volumio volumio[1507]: [Vollibrespot] : error 400 for uri hm://autoplay-enabled/query?uri=spotify:search:%E9%9D%92%E6%98%A5%E3%81%AE%E5%BD%B1 Dec 24 20:01:30 volumio volumio[1507]: [Vollibrespot] : AutoplayError: MercuryError Dec 24 20:01:30 volumio vtcs[1545]: [2025-12-24 20:01:30] [info] asio async_shutdown error: asio.misc:2 (End of file) Dec 24 20:01:30 volumio volumio[1507]: [Vollibrespot] : Event: GotToken { token: Token { access_token: "BQBgT3f0oPbowpdBsJxO3fCWqPWUFyZIU700fgxHngKskHLujahsGNekd2au7itrCWV0qJh7Eux2k8yXH0x8ekgbVHQ6xWyyk0mqkn8w553dbxip_VTtHyYOnUAmWhB822E9g2K_tome8OH-cv7VjpFPOu2teVDS_HSXEq9-CucFXo3ornenRMot7XosD8wnlaRKrpPExwX7dwqEmF0z7uz8AKo3nYdFSiPCxA87-pEqluA", expires_in: 3600, token_type: "Bearer", scope: ["streaming", "user-modify-playback-state", "user-read-playback-state", "user-read-currently-playing", "user-read-private"] } } Dec 24 20:01:30 volumio volumio[1507]: [Vollibrespot] : Loading <青春の影> with Spotify URI Dec 24 20:01:30 volumio sudo[13488]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-24 20:00 Dec 24 20:01:30 volumio sudo[13488]: 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="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"