-- Logs begin at Thu 2025-02-27 13:32:29 UTC, end at Sun 2025-03-16 12:32:36 UTC. -- Mar 16 12:31:37 volumio volumio[1004]: info: Pushing metadata Mar 16 12:31:37 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Mar 16 12:31:37 volumio volumio[1004]: info: CoreStateMachine::pushState Mar 16 12:31:37 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Mar 16 12:31:37 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:31:37 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Mar 16 12:31:37 volumio volumio[1004]: info: CoreStateMachine::pushState Mar 16 12:31:37 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Mar 16 12:31:37 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:31:38 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Mar 16 12:31:38 volumio volumio[1004]: info: CoreStateMachine::pushState Mar 16 12:31:38 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Mar 16 12:31:38 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:31:46 volumio volumio[1004]: info: Pushing metadata Mar 16 12:31:46 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Mar 16 12:31:46 volumio volumio[1004]: info: CoreStateMachine::pushState Mar 16 12:31:46 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Mar 16 12:31:46 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:31:46 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Mar 16 12:31:46 volumio volumio[1004]: info: CoreStateMachine::pushState Mar 16 12:31:46 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Mar 16 12:31:46 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:32:08 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Mar 16 12:32:08 volumio volumio[1004]: info: CoreStateMachine::pushState Mar 16 12:32:08 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Mar 16 12:32:08 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:32:08 volumio volumio[1004]: info: Pushing metadata Mar 16 12:32:08 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Mar 16 12:32:08 volumio volumio[1004]: info: CoreStateMachine::pushState Mar 16 12:32:08 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Mar 16 12:32:08 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:32:08 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Mar 16 12:32:08 volumio volumio[1004]: info: CoreStateMachine::pushState Mar 16 12:32:08 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Mar 16 12:32:08 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:32:08 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Mar 16 12:32:08 volumio volumio[1004]: info: CoreStateMachine::pushState Mar 16 12:32:08 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Mar 16 12:32:08 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:32:08 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Mar 16 12:32:08 volumio volumio[1004]: info: CoreStateMachine::pushState Mar 16 12:32:08 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Mar 16 12:32:08 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:32:08 volumio vtcs[1578]: VTCS: PCM: plughw:2,0 RATE: 44100 FORMAT: S16_LE CHANNELS: 2 PERIODSIZE: 4096 BUFFERSIZE: 32768 Mar 16 12:32:08 volumio volumio[1004]: info: Pushing metadata Mar 16 12:32:08 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Mar 16 12:32:08 volumio volumio[1004]: info: CoreStateMachine::pushState Mar 16 12:32:08 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Mar 16 12:32:08 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:32:08 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Mar 16 12:32:08 volumio volumio[1004]: info: CoreStateMachine::pushState Mar 16 12:32:08 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Mar 16 12:32:08 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:32:08 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Mar 16 12:32:08 volumio volumio[1004]: info: CoreStateMachine::pushState Mar 16 12:32:08 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Mar 16 12:32:08 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:32:08 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Mar 16 12:32:08 volumio volumio[1004]: info: CoreStateMachine::pushState Mar 16 12:32:08 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Mar 16 12:32:08 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:32:08 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Mar 16 12:32:08 volumio volumio[1004]: info: CoreStateMachine::pushState Mar 16 12:32:08 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Mar 16 12:32:08 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:32:08 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Mar 16 12:32:08 volumio volumio[1004]: info: CoreStateMachine::pushState Mar 16 12:32:08 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Mar 16 12:32:08 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:32:08 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Mar 16 12:32:08 volumio volumio[1004]: info: CoreStateMachine::pushState Mar 16 12:32:08 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Mar 16 12:32:08 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:32:08 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Mar 16 12:32:08 volumio volumio[1004]: info: CoreStateMachine::pushState Mar 16 12:32:08 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Mar 16 12:32:08 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:32:10 volumio vtcs[1578]: VTCS: PCM: plughw:2,0 RATE: 44100 FORMAT: S16_LE CHANNELS: 2 PERIODSIZE: 4096 BUFFERSIZE: 32768 Mar 16 12:32:12 volumio vtcs[1578]: [2025-03-16 12:32:12] [info] asio async_shutdown error: asio.misc:2 (End of file) Mar 16 12:32:12 volumio vtcs[1578]: [2025-03-16 12:32:12.655] [tisoc] [error] [logger.cpp:25] [operation_curl.cpp:257] curl_easy_perform failed: Transferred a partial file - transfer closed with 524288 bytes remaining to read Mar 16 12:32:12 volumio vtcs[1578]: [2025-03-16 12:32:12.656] [tisoc] [error] [logger.cpp:25] [http_stream_fetcher.cpp:162] Content-Range is missing from response! Mar 16 12:32:12 volumio vtcs[1578]: VTCS: PCM: plughw:2,0 RATE: 44100 FORMAT: S16_LE CHANNELS: 2 PERIODSIZE: 4096 BUFFERSIZE: 32768 Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreStateMachine::pushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:32:17 volumio volumio[1004]: info: Pushing metadata Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreStateMachine::pushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreStateMachine::pushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreStateMachine::pushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreStateMachine::pushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:32:17 volumio volumio[1004]: info: Pushing metadata Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreStateMachine::pushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreStateMachine::pushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreStateMachine::pushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreStateMachine::pushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreStateMachine::pushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreStateMachine::pushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreStateMachine::pushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreStateMachine::pushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreStateMachine::pushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreStateMachine::pushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:32:17 volumio volumio[1004]: info: Pushing metadata Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreStateMachine::pushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreStateMachine::pushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreStateMachine::pushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreStateMachine::pushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:32:17 volumio volumio[1004]: info: Pushing metadata Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreStateMachine::pushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreStateMachine::pushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreStateMachine::pushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreStateMachine::pushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreStateMachine::pushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreStateMachine::pushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreStateMachine::pushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreStateMachine::pushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreStateMachine::pushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Mar 16 12:32:17 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:32:19 volumio volumio[1004]: info: touch_display: Setting screensaver timeout to 120 seconds. Mar 16 12:32:19 volumio volumio[1004]: info: touch_display: Setting screensaver timeout to 120 seconds. Mar 16 12:32:23 volumio vtcs[1578]: [2025-03-16 12:32:23] [info] asio async_shutdown error: asio.misc:2 (End of file) Mar 16 12:32:26 volumio volumio[1252]: [Vollibrespot] : Connecting to AP "ap-gae2.spotify.com:443" Mar 16 12:32:27 volumio volumio[1252]: [Vollibrespot] : Authenticated as "nnq62i9wk8g7gsn762utdsmoj" ! Mar 16 12:32:27 volumio volumio[1252]: [Vollibrespot] : Using alsa sink Mar 16 12:32:27 volumio volumio[1252]: [Vollibrespot] : Metadata pipe established Mar 16 12:32:27 volumio volumio[1252]: [Vollibrespot] : Event: Volume { volume_to_mixer: 46910 } Mar 16 12:32:27 volumio volumio[1252]: [Vollibrespot] : Country: "KR" Mar 16 12:32:27 volumio volumio[1252]: [Vollibrespot] : Fetching autoplay context uri Mar 16 12:32:27 volumio volumio[1252]: [Vollibrespot] : Event: SessionActive { became_active_at: 1742128347337 } Mar 16 12:32:27 volumio volumio[1252]: [Vollibrespot] : SessionActive! Mar 16 12:32:27 volumio volumio[1252]: [Vollibrespot] : Event: PlaybackStarted { track_id: SpotifyId { id: 236997914418560919439909417487807640430, audio_type: Track } } Mar 16 12:32:27 volumio volumio[1252]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 236997914418560919439909417487807640430, audio_type: Track } } Mar 16 12:32:27 volumio volumio[1252]: [Vollibrespot] : Autoplay uri resolved to <"spotify:station:playlist:2cr9ovAEskjlam4XvTIuEe"> Mar 16 12:32:27 volumio volumio[1252]: [Vollibrespot] : Resolving uri "spotify:station:playlist:2cr9ovAEskjlam4XvTIuEe" Mar 16 12:32:27 volumio volumio[1252]: [Vollibrespot] : Event: GotToken { token: Token { access_token: "BQCvMMxkccH57G2hTsJLNlGFofS_DcK13pnHJ0NHIC-Wn_2HbFRAHWxZQuoCTn-uDE14KGwvsWJ37FlTP4OQnshRPRh2uKIVBdout6MM8WGKNv4REdhfAUbYbiBqw8JAtbIOar9HNIp0p4xyf6MtrC8_HaiZXFt2oxxb0OFpumInPoJMMXWk0ben2F8SfdZ1Ru9vKJtNndPqJj2iNKG8YXzw4ucdd-2_EU4aOZLFdSaMYj2VI9Z8Sj4OBw", expires_in: 3600, token_type: "Bearer", scope: ["streaming", "user-read-playback-state", "user-modify-playback-state", "user-read-currently-playing", "user-read-private"] } } Mar 16 12:32:27 volumio volumio[1252]: [Vollibrespot] : Loading with Spotify URI Mar 16 12:32:27 volumio volumio[1252]: [Vollibrespot] : Resolved 50 tracks from <"spotify:playlist:2cr9ovAEskjlam4XvTIuEe"> Mar 16 12:32:30 volumio volumio[1252]: [Vollibrespot] : (439170 ms) loaded Mar 16 12:32:30 volumio volumio[1252]: [Vollibrespot] : Event: PlaybackStopped { track_id: SpotifyId { id: 236997914418560919439909417487807640430, audio_type: Track } } Mar 16 12:32:30 volumio volumio[1252]: [Vollibrespot] : Fetching autoplay context uri Mar 16 12:32:30 volumio volumio[1252]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 236997914418560919439909417487807640430, audio_type: Track }, track_id: SpotifyId { id: 120862876568245523412009362573505912044, audio_type: Track } } Mar 16 12:32:30 volumio volumio[1252]: [Vollibrespot] : error 400 for uri hm://autoplay-enabled/query?uri=spotify:search:%EA%B9%80%EB%8F%99%EB%A5%A0 Mar 16 12:32:30 volumio volumio[1252]: [Vollibrespot] : Loading with Spotify URI Mar 16 12:32:30 volumio volumio[1252]: [Vollibrespot] : AutoplayError: MercuryError Mar 16 12:32:30 volumio volumio[1252]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 120862876568245523412009362573505912044, audio_type: Track } } Mar 16 12:32:30 volumio volumio[1252]: [Vollibrespot] : (287741 ms) loaded Mar 16 12:32:31 volumio volumio[1252]: [Vollibrespot] : Alsa error PCM open ALSA function 'snd_pcm_open' failed with error 'EBUSY: Device or resource busy' Mar 16 12:32:31 volumio volumio[1252]: [Vollibrespot] : Could not start audio: Alsa error: PCM open failed Mar 16 12:32:31 volumio volumio[1252]: [Vollibrespot] : Alsa error PCM open ALSA function 'snd_pcm_open' failed with error 'EBUSY: Device or resource busy' Mar 16 12:32:31 volumio volumio[1252]: [Vollibrespot] : Could not start audio: Alsa error: PCM open failed Mar 16 12:32:31 volumio volumio[1252]: [Vollibrespot] : Alsa error PCM open ALSA function 'snd_pcm_open' failed with error 'EBUSY: Device or resource busy' Mar 16 12:32:31 volumio volumio[1252]: [Vollibrespot] : Could not start audio: Alsa error: PCM open failed Mar 16 12:32:31 volumio volumio[1252]: thread '' panicked at 'called `Option::unwrap()` on a `None` value', /home/volumio/.cargo/git/checkouts/librespot-6f197fd632ef9380/08d8bcc/playback/src/audio_backend/alsa.rs:129:35 Mar 16 12:32:31 volumio volumio[1252]: stack backtrace: Mar 16 12:32:31 volumio volumio[1252]: 0: 0xa14c40 - std::backtrace_rs::backtrace::libunwind::trace::h9be44abcd6023537 Mar 16 12:32:31 volumio volumio[1252]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/../../backtrace/src/backtrace/libunwind.rs:93:5 Mar 16 12:32:31 volumio volumio[1252]: 1: 0xa14c40 - std::backtrace_rs::backtrace::trace_unsynchronized::he588770997e640ec Mar 16 12:32:31 volumio volumio[1252]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 Mar 16 12:32:31 volumio volumio[1252]: 2: 0xa14c40 - std::sys_common::backtrace::_print_fmt::h2a5bea2fa6ce9b3d Mar 16 12:32:31 volumio volumio[1252]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/sys_common/backtrace.rs:66:5 Mar 16 12:32:31 volumio volumio[1252]: 3: 0xa14c40 - ::fmt::hf1c91d83d30a63b5 Mar 16 12:32:31 volumio volumio[1252]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/sys_common/backtrace.rs:45:22 Mar 16 12:32:31 volumio volumio[1252]: 4: 0xa3ad68 - core::fmt::write::he7b81f119cac9e82 Mar 16 12:32:31 volumio volumio[1252]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/core/src/fmt/mod.rs:1196:17 Mar 16 12:32:31 volumio volumio[1252]: 5: 0xa0e5d4 - std::io::Write::write_fmt::hd6a547671801193b Mar 16 12:32:31 volumio volumio[1252]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/io/mod.rs:1654:15 Mar 16 12:32:31 volumio volumio[1252]: 6: 0xa16728 - std::sys_common::backtrace::_print::hcf4a7819f0b4198f Mar 16 12:32:31 volumio volumio[1252]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/sys_common/backtrace.rs:48:5 Mar 16 12:32:31 volumio volumio[1252]: 7: 0xa16728 - std::sys_common::backtrace::print::hb4deb0b578e6c939 Mar 16 12:32:31 volumio volumio[1252]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/sys_common/backtrace.rs:35:9 Mar 16 12:32:31 volumio volumio[1252]: 8: 0xa16728 - std::panicking::default_hook::{{closure}}::h7d65a85d73ca528f Mar 16 12:32:31 volumio volumio[1252]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:295:22 Mar 16 12:32:31 volumio volumio[1252]: 9: 0xa1623c - std::panicking::default_hook::h3b931251a9c4b1f6 Mar 16 12:32:31 volumio volumio[1252]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:314:9 Mar 16 12:32:31 volumio volumio[1252]: 10: 0xa16f44 - std::panicking::rust_panic_with_hook::h52cc4877d8c74a8b Mar 16 12:32:31 volumio volumio[1252]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:698:17 Mar 16 12:32:31 volumio volumio[1252]: 11: 0xa16c8c - std::panicking::begin_panic_handler::{{closure}}::h7546eed0ba906ec0 Mar 16 12:32:31 volumio volumio[1252]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:586:13 Mar 16 12:32:31 volumio volumio[1252]: 12: 0xa151bc - std::sys_common::backtrace::__rust_end_short_backtrace::h32ad68afde8ac292 Mar 16 12:32:31 volumio volumio[1252]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/sys_common/backtrace.rs:138:18 Mar 16 12:32:31 volumio volumio[1252]: 13: 0xa169ec - rust_begin_unwind Mar 16 12:32:31 volumio volumio[1252]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:584:5 Mar 16 12:32:31 volumio volumio[1252]: 14: 0x4f9e2c - core::panicking::panic_fmt::h43e2baa6a2dcb05a Mar 16 12:32:31 volumio volumio[1252]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/core/src/panicking.rs:142:14 Mar 16 12:32:31 volumio volumio[1252]: 15: 0x4f9cfc - core::panicking::panic::h19d1974f0ce55bbc Mar 16 12:32:31 volumio volumio[1252]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/core/src/panicking.rs:48:5 Mar 16 12:32:31 volumio volumio[1252]: 16: 0x6349e8 - ::write::ha9de3f3ca1151253 Mar 16 12:32:31 volumio volumio[1252]: 17: 0x60fe38 - ::poll::h496db812513449d6 Mar 16 12:32:31 volumio volumio[1252]: 18: 0x539e50 - futures::task_impl::std::set::h5b276d7138d305a3 Mar 16 12:32:31 volumio volumio[1252]: 19: 0x539844 - futures::task_impl::std::ThreadNotify::with_current::h91e2a1e4a82a952d Mar 16 12:32:31 volumio volumio[1252]: 20: 0x539714 - futures::future::Future::wait::hd6e607993d6d71e9 Mar 16 12:32:31 volumio volumio[1252]: 21: 0x542a0c - std::sys_common::backtrace::__rust_begin_short_backtrace::hf0a3a4dca9c5761e Mar 16 12:32:31 volumio volumio[1252]: 22: 0x55cd3c - core::ops::function::FnOnce::call_once{{vtable.shim}}::he2d346937b20b04f Mar 16 12:32:31 volumio volumio[1252]: 23: 0xa19f8c - as core::ops::function::FnOnce>::call_once::h7a75751708f76c39 Mar 16 12:32:31 volumio volumio[1252]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/alloc/src/boxed.rs:1872:9 Mar 16 12:32:31 volumio volumio[1252]: 24: 0xa19f8c - as core::ops::function::FnOnce>::call_once::ha623c3617a4a451b Mar 16 12:32:31 volumio volumio[1252]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/alloc/src/boxed.rs:1872:9 Mar 16 12:32:31 volumio volumio[1252]: 25: 0xa19f8c - std::sys::unix::thread::Thread::new::thread_start::h751d26e6bfaf6355 Mar 16 12:32:31 volumio volumio[1252]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/sys/unix/thread.rs:108:17 Mar 16 12:32:34 volumio volumio[1004]: info: Pushing metadata Mar 16 12:32:34 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Mar 16 12:32:34 volumio volumio[1004]: info: CoreStateMachine::pushState Mar 16 12:32:34 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Mar 16 12:32:34 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:32:34 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Mar 16 12:32:34 volumio volumio[1004]: info: CoreStateMachine::pushState Mar 16 12:32:34 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Mar 16 12:32:34 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:32:34 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Mar 16 12:32:34 volumio volumio[1004]: info: CoreStateMachine::pushState Mar 16 12:32:34 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Mar 16 12:32:34 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:32:34 volumio volumio[1004]: [SpotifyConnect] Volume Spotify: 71.58007171740292 Volumio: 72 Mar 16 12:32:34 volumio volumio[1004]: [SpotifyConnect] A connect session has begun Mar 16 12:32:34 volumio volumio[1004]: info: Acquiring new spotify session Mar 16 12:32:34 volumio volumio[1004]: info: CoreCommandRouter::volumioStop Mar 16 12:32:34 volumio volumio[1004]: info: CoreStateMachine::stop Mar 16 12:32:34 volumio volumio[1004]: info: CoreStateMachine::serviceStop Mar 16 12:32:34 volumio volumio[1004]: info: CoreCommandRouter::serviceStop Mar 16 12:32:34 volumio volumio[1004]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 16 12:32:34 volumio volumio[1004]: TypeError: Cannot read property 'then' of undefined Mar 16 12:32:34 volumio volumio[1004]: at SpotConnEvents.SpotConn.on (/data/plugins/music_service/spop/index.js:2095:31) Mar 16 12:32:34 volumio volumio[1004]: at emitNone (events.js:106:13) Mar 16 12:32:34 volumio volumio[1004]: at SpotConnEvents.emit (events.js:208:7) Mar 16 12:32:34 volumio volumio[1004]: at SpotConnEvents.parseData (/data/plugins/music_service/spop/SpotConnController.js:81:16) Mar 16 12:32:34 volumio volumio[1004]: at Socket.SpotConnEvents._udpsource.on.msg (/data/plugins/music_service/spop/SpotConnController.js:15:12) Mar 16 12:32:34 volumio volumio[1004]: at emitTwo (events.js:126:13) Mar 16 12:32:34 volumio volumio[1004]: at Socket.emit (events.js:214:7) Mar 16 12:32:34 volumio volumio[1004]: at UDP.onMessage [as onmessage] (dgram.js:659:8) Mar 16 12:32:34 volumio volumio[1004]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 16 12:32:36 volumio sudo[11932]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-16 12:31 Mar 16 12:32:36 volumio sudo[11932]: 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="307f91893fca011201acba9973e1c41bd3a0ad5d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Sep 30 22:42:09 CEST 2021" VOLUMIO_VERSION="2.915" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="02ee61bb422c79b6e5c0b6d1847a78d9"