volspotconnect2.service: Main process exited, code=exited, status=127/n/a
volspotconnect2.service: Failed with result 'exit-code'.
volspotconnect2.service: Service RestartSec=2s expired, scheduling restart.
volspotconnect2.service: Scheduled restart job, restart counter is at 1.
Stopped Volspotconnect2 Daemon.
Started Volspotconnect2 Daemon.
vollibrespot v0.2.3 769ce77 2021-06-04 (librespot 08d8bcc 2020-10-07) -- Built On 2021-06-04
Reading Config from "volspotify.toml"
[Vollibrespot] : Using Alsa backend with device: volumio
[Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Other, message: "No such device" }
[Vollibrespot] : Connecting to AP "guc3-accesspoint-a-mnlj.ap.spotify.com:443"
[Vollibrespot] : Authenticated as "dolijr" !
[Vollibrespot] : Using alsa sink
[Vollibrespot] : Metadata pipe established
[Vollibrespot] : Country: "US"
[Vollibrespot] : Event: Volume { volume_to_mixer: 65535 }
[Vollibrespot] : Event: SessionActive { became_active_at: 1636838142915 }
[Vollibrespot] : SessionActive!
[Vollibrespot] : Fetching autoplay context uri
[Vollibrespot] : Event: PlaybackStarted { track_id: SpotifyId { id: 76458480426230788817860193029457698775, audio_type: Track } }
[Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 76458480426230788817860193029457698775, audio_type: Track } }
[Vollibrespot] : Autoplay uri resolved to <"spotify:station:playlist:4d6wvpHsCFr9alknIx25DX">
[Vollibrespot] : Resolving uri "spotify:station:playlist:4d6wvpHsCFr9alknIx25DX"
[Vollibrespot] : Event: GotToken { token: Token { access_token: "BQC7uYmbOgdG5-lcpsu-VMpOBK_IDu1RPNKQaf6DZvCgT85PWYTCYJy8cO1rXUAR4d52hd4QYqeukt6YQ_OC447WD8ZOKKDWGPMbDu2jbwJlPrr0D3DEzPrN1dpNdZN0Xthhlr04iu5lfq97KDwrX3uS8HxaprUz", expires_in: 3600, token_type: "Bearer", scope: ["streaming", "user-read-playback-state", "user-modify-playback-state", "user-read-currently-playing", "user-read-private"] } }
[Vollibrespot] : Loading <Portrait Of Jennie> with Spotify URI <spotify:track:1KxvJx6fzcgUsC26AKlxl5>
[Vollibrespot] : Resolved 50 tracks from <"spotify:playlist:4d6wvpHsCFr9alknIx25DX">
[Vollibrespot] : <Portrait Of Jennie> (337400 ms) loaded
[Vollibrespot] : Event: SinkActive
[Vollibrespot] : Event: Pause { track_id: SpotifyId { id: 76458480426230788817860193029457698775, audio_type: Track }, position_ms: 19207 }
[Vollibrespot] : Event: SinkInactive
[Vollibrespot] : Event: PlaybackStopped { track_id: SpotifyId { id: 76458480426230788817860193029457698775, audio_type: Track } }
[Vollibrespot] : Event: Play { track_id: SpotifyId { id: 76458480426230788817860193029457698775, audio_type: Track }, position_ms: 19450 }
[Vollibrespot] : Event: SinkActive
L: =========================================================================
L: ======================================================================
L: ===============================================================tify:track:3Hgd5R9MEfHdBfqK0T4vr8>
R: =====================================6159 ms) loaded
[Vollibrespot] : Event: Next { track_id: SpotifyId { id: 161497150738589725709547886057106128606, audio_type: Track } }
L: =========================================rack_id: SpotifyId { id: 76458480426230788817860193029457698775, audio_tR: ============================================50738589725709547886057106128606, audio_type: Track } }
ALSA lib pcm.c:8424:(snd_pcm_recover) underrun occurred
ALSA lib pcm.c:8427:(snd_pcm_recover) cannot recovery from underrun, prepare failed: Device or resource busy
thread '<unnamed>' panicked at 'called `Result::unwrap()` on an `Err` value: Error("snd_pcm_recover", Sys(EBUSY))', /build/cache/git/checkouts/librespot-6f197fd632ef9380/08d8bcc/playback/src/audio_backend/alsa.rs:134:53
stack backtrace:
   0:   0x983040 - std::backtrace_rs::backtrace::libunwind::trace::h4e6fc6cdc5d76862
                       at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/../../backtrace/src/backtrace/libunwind.rs:90:5
   1:   0x983040 - std::backtrace_rs::backtrace::trace_unsynchronized::h1c665adaaf480d86
                       at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5
   2:   0x983040 - std::sys_common::backtrace::_print_fmt::h39ff02745027e048
                       at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/sys_common/backtrace.rs:67:5
   3:   0x983040 - <std::sys_common::backtrace::_print::DisplayBacktrace as core::fmt::Display>::fmt::hc83ae421f857c6ae
                       at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/sys_common/backtrace.rs:46:22
   4:   0x9a46a4 - core::fmt::write::h59f33d28fc1a9cf5
                       at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/core/src/fmt/mod.rs:1092:17
   5:   0x97bfec - std::io::Write::write_fmt::h96774110acd52d88
                       at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/io/mod.rs:1572:15
   6:   0x98574c - std::sys_common::backtrace::_print::h28b9431057012c44
                       at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/sys_common/backtrace.rs:49:5
   7:   0x98574c - std::sys_common::backtrace::print::h28173b7ebfe4a4ac
                       at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/sys_common/backtrace.rs:36:9
   8:   0x98574c - std::panicking::default_hook::{{closure}}::h12f82aa3def34336
                       at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/panicking.rs:208:50
   9:   0x98512c - std::panicking::default_hook::hee29440c29c1cc2e
                       at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/panicking.rs:225:9
  10:   0x985dac - std::panicking::rust_panic_with_hook::he7d3b6e3eee77e72
                       at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/panicking.rs:591:17
  11:   0x985928 - std::panicking::begin_panic_handler::{{closure}}::h7faba6b3632756a6
                       at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/panicking.rs:497:13
  12:   0x9835bc - std::sys_common::backtrace::__rust_end_short_backtrace::hdde0f982a7a71ce9
                       at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/sys_common/backtrace.rs:141:18
  13:   0x985870 - rust_begin_unwind
                       at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/panicking.rs:493:5
  14:   0x45f21c - core::panicking::panic_fmt::h97b6949f75a63095
                       at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/core/src/panicking.rs:92:14
  15:   0x45f120 - core::option::expect_none_failed::h4525992415ba2383
                       at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/core/src/option.rs:1329:5
  16:   0x599c24 - <librespot_playback::audio_backend::alsa::AlsaSink as librespot_playback::audio_backend::Sink>::write::h2dc31352d1009910
  17:   0x57c588 - <librespot_playback::player::PlayerInternal as futures::future::Future>::poll::h8080398afd4135f1
  18:   0x49f3ac - futures::task_impl::std::set::h5f18eafe66b6e0ce
  19:   0x48bc88 - futures::task_impl::Spawn<T>::poll_future_notify::hb5fbdb91a4afa239
  20:   0x4b0ad0 - futures::future::Future::wait::h720e8c679b25c377
  21:   0x4b8330 - std::sys_common::backtrace::__rust_begin_short_backtrace::h71e7578ce733f5f0
  22:   0x4ab004 - core::ops::function::FnOnce::call_once{{vtable.shim}}::hf986f3b67bd2f3cd
  23:   0x989430 - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::h55699fe2dcd01fb2
                       at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/alloc/src/boxed.rs:1546:9
  24:   0x989430 - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::h767211a722faedbf
                       at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/alloc/src/boxed.rs:1546:9
L:25:   0x989430 - std::sys::unix::thread::Thread::new::thread_start::h4c3152afb00cbdcf
R:                     at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/sys/unix/thread.rs:71:17