volumio@volumio:/data/plugins/audio_interface/fusiondsp$ journalctl -f -- Logs begin at Thu 2021-07-15 18:20:51 UTC. -- Jul 15 21:44:17 volumio volumio-remote-updater[14580]: [2021-07-15 21:44:17] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1626385457 101 Jul 15 21:45:42 volumio volumio-remote-updater[14580]: [2021-07-15 21:45:42] [disconnect] Disconnect close local:[1000] remote:[1000] Jul 15 21:45:42 volumio systemd[1]: volumio-remote-updater.service: Succeeded. Jul 15 21:45:42 volumio systemd[1]: volumio-remote-updater.service: Service RestartSec=100ms expired, scheduling restart. Jul 15 21:45:42 volumio systemd[1]: volumio-remote-updater.service: Scheduled restart job, restart counter is at 144. Jul 15 21:45:42 volumio systemd[1]: Stopped volumio-remote-updater.service. Jul 15 21:45:42 volumio systemd[1]: Started volumio-remote-updater.service. Jul 15 21:45:42 volumio volumio-remote-updater[14589]: Error: No active session Jul 15 21:45:42 volumio volumio-remote-updater[14589]: [2021-07-15 21:45:42] [connect] Successful connection Jul 15 21:45:42 volumio volumio-remote-updater[14589]: [2021-07-15 21:45:42] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1626385542 101 Jul 15 21:47:02 volumio volumio[14564]: [Vollibrespot] : Fetching autoplay context uri Jul 15 21:47:02 volumio volumio[14564]: [Vollibrespot] : Event: SessionActive { became_active_at: 1626385622857 } Jul 15 21:47:02 volumio volumio[14564]: [Vollibrespot] : SessionActive! Jul 15 21:47:02 volumio volumio[14564]: [Vollibrespot] : Event: PlaybackStarted { track_id: SpotifyId { id: 308931455522672064404307368150213383688, audio_type: Track } } Jul 15 21:47:02 volumio volumio[14564]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 308931455522672064404307368150213383688, audio_type: Track } } Jul 15 21:47:02 volumio volumio[747]: [SpotifyConnect] A connect session has begun Jul 15 21:47:02 volumio volumio[747]: [SpotifyConnect] Device palyback is active! Jul 15 21:47:02 volumio volumio[747]: info: CoreCommandRouter::volumioGetState Jul 15 21:47:02 volumio volumio[747]: info: CorePlayQueue::getTrack 0 Jul 15 21:47:02 volumio volumio[747]: [SpotifyConnect] Currently active: mpd Jul 15 21:47:02 volumio volumio[747]: [SpotifyConnect] Stopping currently active service Jul 15 21:47:02 volumio volumio[747]: info: CoreCommandRouter::volumioStop Jul 15 21:47:02 volumio volumio[747]: info: CoreStateMachine::stop Jul 15 21:47:02 volumio volumio[747]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 15 21:47:02 volumio volumio[747]: UNSET VOLATILE Jul 15 21:47:02 volumio volumio[747]: [SpotifyConnect] Vollibrespot Active Jul 15 21:47:02 volumio volumio[747]: info: CoreCommandRouter::volumioGetState Jul 15 21:47:02 volumio volumio[747]: info: CorePlayQueue::getTrack 0 Jul 15 21:47:02 volumio volumio[747]: [SpotifyConnect] Currently active: mpd Jul 15 21:47:02 volumio volumio[747]: [SpotifyConnect] Setting Volatile state to Volspotconnect2 Jul 15 21:47:02 volumio volumio[747]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 15 21:47:02 volumio volumio[747]: info: CoreCommandRouter::servicePushState Jul 15 21:47:02 volumio volumio[747]: info: CoreStateMachine::pushState Jul 15 21:47:02 volumio volumio[747]: info: CoreCommandRouter::volumioPushState Jul 15 21:47:02 volumio volumio[747]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 15 21:47:02 volumio volumio[747]: info: CorePlayQueue::getTrack 0 Jul 15 21:47:02 volumio volumio[747]: verbose: STATE SERVICE {"status":"stop","service":"volspotconnect2","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"spotify","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2} Jul 15 21:47:02 volumio volumio[747]: verbose: CURRENT POSITION 0 Jul 15 21:47:02 volumio volumio[747]: info: CoreStateMachine::syncState stateService stop Jul 15 21:47:02 volumio volumio[747]: info: CoreStateMachine::syncState currentStatus stop Jul 15 21:47:02 volumio volumio[747]: info: CoreStateMachine::pushState Jul 15 21:47:02 volumio volumio[747]: info: CoreCommandRouter::volumioPushState Jul 15 21:47:02 volumio volumio[747]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 15 21:47:02 volumio volumio[747]: info: No code Jul 15 21:47:02 volumio volumio[747]: info: CoreStateMachine::pushState Jul 15 21:47:02 volumio volumio[747]: info: CoreCommandRouter::volumioPushState Jul 15 21:47:02 volumio volumio[747]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 15 21:47:02 volumio volumio[747]: info: CoreCommandRouter::volumioGetState Jul 15 21:47:02 volumio volumio[747]: [SpotifyConnect] Currently active: undefined Jul 15 21:47:02 volumio volumio[747]: [SpotifyConnect] Pushing new state :: true Jul 15 21:47:02 volumio volumio[747]: info: CoreCommandRouter::servicePushState Jul 15 21:47:02 volumio volumio[747]: info: CoreStateMachine::pushState Jul 15 21:47:02 volumio volumio[747]: info: CoreCommandRouter::volumioPushState Jul 15 21:47:02 volumio volumio[747]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 15 21:47:02 volumio volumio[747]: info: CorePlayQueue::getTrack 0 Jul 15 21:47:02 volumio volumio[747]: verbose: STATE SERVICE {"status":"stop","service":"volspotconnect2","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"spotify","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2} Jul 15 21:47:02 volumio volumio[747]: verbose: CURRENT POSITION 0 Jul 15 21:47:02 volumio volumio[747]: info: CoreStateMachine::syncState stateService stop Jul 15 21:47:02 volumio volumio[747]: info: CoreStateMachine::syncState currentStatus stop Jul 15 21:47:02 volumio volumio[747]: info: CoreStateMachine::pushState Jul 15 21:47:02 volumio volumio[747]: info: CoreCommandRouter::volumioPushState Jul 15 21:47:02 volumio volumio[747]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 15 21:47:02 volumio volumio[747]: info: No code Jul 15 21:47:02 volumio volumio[747]: info: CoreStateMachine::pushState Jul 15 21:47:02 volumio volumio[747]: info: CoreCommandRouter::volumioPushState Jul 15 21:47:02 volumio volumio[747]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 15 21:47:02 volumio volumio[14564]: [Vollibrespot] : Autoplay uri resolved to <"spotify:station:playlist:34nx68uXAiYvsrDdD1giJa"> Jul 15 21:47:02 volumio volumio[14564]: [Vollibrespot] : Resolving uri "spotify:station:playlist:34nx68uXAiYvsrDdD1giJa" Jul 15 21:47:02 volumio volumio[14564]: [Vollibrespot] : Event: GotToken { token: Token { access_token: "BQAvnU_9JfqrksgomRt7pYLyfBn9SBO6ehkc2z7wDPF4hRzGhAWTGnVOnHSSedw5qgt4WgLNRauvxaYh-u1GM6XSDmr2HGP2AIFc3QvIyNXGDDgiNTbEQLlsh7JFWNNK3RgUvc-M8f51NcY3jcBupcbL2mRyEsc3YMmwRA47Q99sC8mETlyvWu8Ndg", expires_in: 3600, token_type: "Bearer", scope: ["streaming", "user-read-playback-state", "user-modify-playback-state", "user-read-currently-playing", "user-read-private"] } } Jul 15 21:47:02 volumio volumio[747]: [SpotifyConnect] BQAvnU_9JfqrksgomRt7pYLyfBn9SBO6ehkc2z7wDPF4hRzGhAWTGnVOnHSSedw5qgt4WgLNRauvxaYh-u1GM6XSDmr2HGP2AIFc3QvIyNXGDDgiNTbEQLlsh7JFWNNK3RgUvc-M8f51NcY3jcBupcbL2mRyEsc3YMmwRA47Q99sC8mETlyvWu8Ndg Jul 15 21:47:02 volumio volumio[14564]: [Vollibrespot] : Loading with Spotify URI Jul 15 21:47:03 volumio volumio[747]: [SpotifyConnect] Setting VLS device_id: c6e56cf6b68c07041768b06e2f29a439e7724b0e Jul 15 21:47:03 volumio volumio[14564]: [Vollibrespot] : Resolved 50 tracks from <"spotify:playlist:34nx68uXAiYvsrDdD1giJa"> Jul 15 21:47:03 volumio volumio[14564]: [Vollibrespot] : (401773 ms) loaded Jul 15 21:47:03 volumio volumio[14564]: [Vollibrespot] : Event: PlaybackStopped { track_id: SpotifyId { id: 308931455522672064404307368150213383688, audio_type: Track } } Jul 15 21:47:03 volumio volumio[747]: [SpotifyConnect] Device palyback is inactive Jul 15 21:47:03 volumio volumio[747]: [SpotifyConnect] Device Session is_active: true Jul 15 21:47:07 volumio volumio-remote-updater[14589]: [2021-07-15 21:47:07] [disconnect] Disconnect close local:[1000] remote:[1000] Jul 15 21:47:07 volumio systemd[1]: volumio-remote-updater.service: Succeeded. Jul 15 21:47:08 volumio systemd[1]: volumio-remote-updater.service: Service RestartSec=100ms expired, scheduling restart. Jul 15 21:47:08 volumio systemd[1]: volumio-remote-updater.service: Scheduled restart job, restart counter is at 145. Jul 15 21:47:08 volumio systemd[1]: Stopped volumio-remote-updater.service. Jul 15 21:47:08 volumio systemd[1]: Started volumio-remote-updater.service. Jul 15 21:47:08 volumio volumio-remote-updater[14601]: Error: No active session Jul 15 21:47:08 volumio volumio-remote-updater[14601]: [2021-07-15 21:47:08] [connect] Successful connection Jul 15 21:47:08 volumio volumio-remote-updater[14601]: [2021-07-15 21:47:08] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1626385628 101 Jul 15 21:47:13 volumio volumio[14564]: [Vollibrespot] : Event: Play { track_id: SpotifyId { id: 308931455522672064404307368150213383688, audio_type: Track }, position_ms: 329920 } Jul 15 21:47:13 volumio volumio[747]: [SpotifyConnect] play Jul 15 21:47:13 volumio kernel: traps: camilladsp[14604] trap invalid opcode ip:56334dcfc2f8 sp:7fff331ba460 error:0 in camilladsp[56334dcd1000+2c8000] Jul 15 21:47:13 volumio volumio[14564]: thread '' panicked at 'called `Result::unwrap()` on an `Err` value: Error("snd_pcm_recover", Sys(ENODEV))', /build/cache/git/checkouts/librespot-6f197fd632ef9380/08d8bcc/playback/src/audio_backend/alsa.rs:134:53 Jul 15 21:47:13 volumio volumio[14564]: stack backtrace: Jul 15 21:47:13 volumio volumio[14564]: 0: 0x558e5c3a8240 - std::backtrace_rs::backtrace::libunwind::trace::h63b7a90188ab5fb3 Jul 15 21:47:13 volumio volumio[14564]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/../../backtrace/src/backtrace/libunwind.rs:90:5 Jul 15 21:47:13 volumio volumio[14564]: 1: 0x558e5c3a8240 - std::backtrace_rs::backtrace::trace_unsynchronized::h80aefbf9b851eca7 Jul 15 21:47:13 volumio volumio[14564]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 Jul 15 21:47:13 volumio volumio[14564]: 2: 0x558e5c3a8240 - std::sys_common::backtrace::_print_fmt::hbef05ae4237a4d72 Jul 15 21:47:13 volumio volumio[14564]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/sys_common/backtrace.rs:67:5 Jul 15 21:47:13 volumio volumio[14564]: 3: 0x558e5c3a8240 - ::fmt::h28abce2fdb9884c2 Jul 15 21:47:13 volumio volumio[14564]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/sys_common/backtrace.rs:46:22 Jul 15 21:47:13 volumio volumio[14564]: 4: 0x558e5c3c835f - core::fmt::write::h3b84512577ca38a8 Jul 15 21:47:13 volumio volumio[14564]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/core/src/fmt/mod.rs:1092:17 Jul 15 21:47:13 volumio volumio[14564]: 5: 0x558e5c3a1772 - std::io::Write::write_fmt::h465f8feea02e2aa1 Jul 15 21:47:13 volumio volumio[14564]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/io/mod.rs:1572:15 Jul 15 21:47:13 volumio volumio[14564]: 6: 0x558e5c3aa595 - std::sys_common::backtrace::_print::h525280ee0d29bdde Jul 15 21:47:13 volumio volumio[14564]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/sys_common/backtrace.rs:49:5 Jul 15 21:47:13 volumio volumio[14564]: 7: 0x558e5c3aa595 - std::sys_common::backtrace::print::h1f0f5b9f3ef8fb78 Jul 15 21:47:13 volumio volumio[14564]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/sys_common/backtrace.rs:36:9 Jul 15 21:47:13 volumio volumio[14564]: 8: 0x558e5c3aa595 - std::panicking::default_hook::{{closure}}::ha5838f6faa4a5a8f Jul 15 21:47:13 volumio volumio[14564]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/panicking.rs:208:50 Jul 15 21:47:13 volumio volumio[14564]: 9: 0x558e5c3aa043 - std::panicking::default_hook::hfb9fe98acb0dcb3b Jul 15 21:47:13 volumio volumio[14564]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/panicking.rs:225:9 Jul 15 21:47:13 volumio volumio[14564]: 10: 0x558e5c3aab9d - std::panicking::rust_panic_with_hook::hb89f5f19036e6af8 Jul 15 21:47:13 volumio volumio[14564]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/panicking.rs:591:17 Jul 15 21:47:13 volumio volumio[14564]: 11: 0x558e5c3aa737 - std::panicking::begin_panic_handler::{{closure}}::h119e7951427f41da Jul 15 21:47:13 volumio volumio[14564]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/panicking.rs:497:13 Jul 15 21:47:13 volumio volumio[14564]: 12: 0x558e5c3a86fc - std::sys_common::backtrace::__rust_end_short_backtrace::hce386c44bf47a128 Jul 15 21:47:13 volumio volumio[14564]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/sys_common/backtrace.rs:141:18 Jul 15 21:47:13 volumio volumio[14564]: 13: 0x558e5c3aa699 - rust_begin_unwind Jul 15 21:47:13 volumio volumio[14564]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/panicking.rs:493:5 Jul 15 21:47:13 volumio volumio[14564]: 14: 0x558e5bea1ba1 - core::panicking::panic_fmt::h2242888e8769cd33 Jul 15 21:47:13 volumio volumio[14564]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/core/src/panicking.rs:92:14 Jul 15 21:47:13 volumio volumio[14564]: 15: 0x558e5bea1a93 - core::option::expect_none_failed::hb1edf11f73e63728 Jul 15 21:47:13 volumio volumio[14564]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/core/src/option.rs:1329:5 Jul 15 21:47:13 volumio volumio[14564]: 16: 0x558e5bfdabcb - ::write::ha4d18be3b3638b02 Jul 15 21:47:13 volumio volumio[14564]: 17: 0x558e5bfbcd2b - ::poll::h61630aeda8a20401 Jul 15 21:47:13 volumio volumio[14564]: 18: 0x558e5bee63c4 - futures::task_impl::std::set::h3864c827fcc50de8 Jul 15 21:47:13 volumio volumio[14564]: 19: 0x558e5bed3336 - futures::task_impl::Spawn::poll_future_notify::h89d0d70c94a22837 Jul 15 21:47:13 volumio volumio[14564]: 20: 0x558e5bef620e - futures::future::Future::wait::hd50c58f91d3aecc0 Jul 15 21:47:13 volumio volumio[14564]: 21: 0x558e5befc683 - std::sys_common::backtrace::__rust_begin_short_backtrace::h39cffa89de27e079 Jul 15 21:47:13 volumio volumio[14564]: 22: 0x558e5bef063c - core::ops::function::FnOnce::call_once{{vtable.shim}}::ha8294c297b7b215a Jul 15 21:47:13 volumio volumio[14564]: 23: 0x558e5c3ae0da - as core::ops::function::FnOnce>::call_once::hc444a77f8dd8d825 Jul 15 21:47:13 volumio volumio[14564]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/alloc/src/boxed.rs:1546:9 Jul 15 21:47:13 volumio volumio[14564]: 24: 0x558e5c3ae0da - as core::ops::function::FnOnce>::call_once::h8b68a0a9a2093dfc Jul 15 21:47:13 volumio volumio[14564]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/alloc/src/boxed.rs:1546:9 Jul 15 21:47:13 volumio volumio[14564]: 25: 0x558e5c3ae0da - std::sys::unix::thread::Thread::new::thread_start::hb95464447f61f48d Jul 15 21:47:13 volumio volumio[14564]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/sys/unix/thread.rs:71:17 Jul 15 21:47:13 volumio volumio[14564]: 26: 0x7f1909dd5fa3 - Jul 15 21:47:13 volumio volumio[14564]: 27: 0x7f1909b7c4cf - clone Jul 15 21:47:13 volumio volumio[14564]: 28: 0x0 - Jul 15 21:47:14 volumio volumio[14564]: [Vollibrespot] : Event: SinkActive Jul 15 21:47:14 volumio volumio[747]: [SpotifyConnect] Ek Chanchal Shokh Haseena Jul 15 21:47:14 volumio volumio[747]: [SpotifyConnect] Pushing metadata Jul 15 21:47:14 volumio volumio[747]: info: CoreCommandRouter::volumioGetState Jul 15 21:47:14 volumio volumio[747]: [SpotifyConnect] Currently active: undefined Jul 15 21:47:14 volumio volumio[747]: [SpotifyConnect] Pushing new state :: true Jul 15 21:47:14 volumio volumio[747]: info: CoreCommandRouter::servicePushState Jul 15 21:47:14 volumio volumio[747]: info: CoreStateMachine::pushState Jul 15 21:47:14 volumio volumio[747]: info: CoreCommandRouter::volumioPushState Jul 15 21:47:14 volumio volumio[747]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 15 21:47:14 volumio volumio[747]: info: CorePlayQueue::getTrack 0 Jul 15 21:47:14 volumio volumio[747]: verbose: STATE SERVICE {"status":"stop","service":"volspotconnect2","title":"Ek Chanchal Shokh Haseena","artist":"Abhijeet","album":"Baaghi (Original Motion Picture Soundtrack)","albumart":"https://i.scdn.co/image/ab67616d0000b27345e69c558f6ccc832ed65303","uri":"spotify:track:74yG0BNv476eeyjvC7GrNu","trackType":"spotify","seek":329920,"duration":402,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2} Jul 15 21:47:14 volumio volumio[747]: verbose: CURRENT POSITION 0 Jul 15 21:47:14 volumio volumio[747]: info: CoreStateMachine::syncState stateService stop Jul 15 21:47:14 volumio volumio[747]: info: CoreStateMachine::syncState currentStatus stop Jul 15 21:47:14 volumio volumio[747]: info: CoreStateMachine::pushState Jul 15 21:47:14 volumio volumio[747]: info: CoreCommandRouter::volumioPushState Jul 15 21:47:14 volumio volumio[747]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 15 21:47:14 volumio volumio[747]: info: No code Jul 15 21:47:14 volumio volumio[747]: info: CoreStateMachine::pushState Jul 15 21:47:14 volumio volumio[747]: info: CoreCommandRouter::volumioPushState Jul 15 21:47:14 volumio volumio[747]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 15 21:47:14 volumio volumio[747]: [SpotifyConnect] Sink acquired Jul 15 21:47:14 volumio volumio[747]: info: CoreCommandRouter::volumioGetState Jul 15 21:47:14 volumio volumio[747]: [SpotifyConnect] Currently active: undefined Jul 15 21:47:14 volumio volumio[747]: [SpotifyConnect] Pushing new state :: true Jul 15 21:47:14 volumio volumio[747]: info: CoreCommandRouter::servicePushState Jul 15 21:47:14 volumio volumio[747]: info: CoreStateMachine::pushState Jul 15 21:47:14 volumio volumio[747]: info: CoreCommandRouter::volumioPushState Jul 15 21:47:14 volumio volumio[747]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 15 21:47:25 volumio volumio[14564]: thread 'main' panicked at 'called `Result::unwrap()` on an `Err` value: SendError("...")', /build/cache/git/checkouts/librespot-6f197fd632ef9380/08d8bcc/playback/src/player.rs:232:61 Jul 15 21:47:25 volumio volumio[14564]: stack backtrace: Jul 15 21:47:25 volumio volumio[14564]: 0: 0x558e5c3a8240 - std::backtrace_rs::backtrace::libunwind::trace::h63b7a90188ab5fb3 Jul 15 21:47:25 volumio volumio[14564]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/../../backtrace/src/backtrace/libunwind.rs:90:5 Jul 15 21:47:25 volumio volumio[14564]: 1: 0x558e5c3a8240 - std::backtrace_rs::backtrace::trace_unsynchronized::h80aefbf9b851eca7 Jul 15 21:47:25 volumio volumio[14564]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 Jul 15 21:47:25 volumio volumio[14564]: 2: 0x558e5c3a8240 - std::sys_common::backtrace::_print_fmt::hbef05ae4237a4d72 Jul 15 21:47:25 volumio volumio[14564]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/sys_common/backtrace.rs:67:5 Jul 15 21:47:25 volumio volumio[14564]: 3: 0x558e5c3a8240 - ::fmt::h28abce2fdb9884c2 Jul 15 21:47:25 volumio volumio[14564]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/sys_common/backtrace.rs:46:22 Jul 15 21:47:25 volumio volumio[14564]: 4: 0x558e5c3c835f - core::fmt::write::h3b84512577ca38a8 Jul 15 21:47:25 volumio volumio[14564]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/core/src/fmt/mod.rs:1092:17 Jul 15 21:47:25 volumio volumio[14564]: 5: 0x558e5c3a1772 - std::io::Write::write_fmt::h465f8feea02e2aa1 Jul 15 21:47:25 volumio volumio[14564]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/io/mod.rs:1572:15 Jul 15 21:47:25 volumio volumio[14564]: 6: 0x558e5c3aa595 - std::sys_common::backtrace::_print::h525280ee0d29bdde Jul 15 21:47:25 volumio volumio[14564]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/sys_common/backtrace.rs:49:5 Jul 15 21:47:25 volumio volumio[14564]: 7: 0x558e5c3aa595 - std::sys_common::backtrace::print::h1f0f5b9f3ef8fb78 Jul 15 21:47:25 volumio volumio[14564]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/sys_common/backtrace.rs:36:9 Jul 15 21:47:25 volumio volumio[14564]: 8: 0x558e5c3aa595 - std::panicking::default_hook::{{closure}}::ha5838f6faa4a5a8f Jul 15 21:47:25 volumio volumio[14564]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/panicking.rs:208:50 Jul 15 21:47:25 volumio volumio[14564]: 9: 0x558e5c3aa043 - std::panicking::default_hook::hfb9fe98acb0dcb3b Jul 15 21:47:25 volumio volumio[14564]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/panicking.rs:225:9 Jul 15 21:47:25 volumio volumio[14564]: 10: 0x558e5c3aab9d - std::panicking::rust_panic_with_hook::hb89f5f19036e6af8 Jul 15 21:47:25 volumio volumio[14564]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/panicking.rs:591:17 Jul 15 21:47:25 volumio volumio[14564]: 11: 0x558e5c3aa737 - std::panicking::begin_panic_handler::{{closure}}::h119e7951427f41da Jul 15 21:47:25 volumio volumio[14564]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/panicking.rs:497:13 Jul 15 21:47:25 volumio volumio[14564]: 12: 0x558e5c3a86fc - std::sys_common::backtrace::__rust_end_short_backtrace::hce386c44bf47a128 Jul 15 21:47:25 volumio volumio[14564]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/sys_common/backtrace.rs:141:18 Jul 15 21:47:25 volumio volumio[14564]: 13: 0x558e5c3aa699 - rust_begin_unwind Jul 15 21:47:25 volumio volumio[14564]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/panicking.rs:493:5 Jul 15 21:47:25 volumio volumio[14564]: 14: 0x558e5bea1ba1 - core::panicking::panic_fmt::h2242888e8769cd33 Jul 15 21:47:25 volumio volumio[14564]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/core/src/panicking.rs:92:14 Jul 15 21:47:25 volumio volumio[14564]: 15: 0x558e5bea1a93 - core::option::expect_none_failed::hb1edf11f73e63728 Jul 15 21:47:25 volumio volumio[14564]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/core/src/option.rs:1329:5 Jul 15 21:47:25 volumio volumio[14564]: 16: 0x558e5bfb9b72 - librespot_playback::player::Player::pause::hf3c84564dff580f0 Jul 15 21:47:25 volumio volumio[14564]: 17: 0x558e5bf8a042 - librespot_connect::spirc::SpircTask::handle_pause::h63751072c5fd94ea Jul 15 21:47:25 volumio volumio[14564]: 18: 0x558e5bf88fdc - ::poll::h644527f107dfe1b6 Jul 15 21:47:25 volumio volumio[14564]: 19: 0x558e5beca6e9 - ::poll::h789d742187cbd50d Jul 15 21:47:25 volumio volumio[14564]: 20: 0x558e5bee6133 - futures::task_impl::std::set::h188eb9e1a8950863 Jul 15 21:47:25 volumio volumio[14564]: 21: 0x558e5befdc0d - std::thread::local::LocalKey::with::hb45052fc6a2ae633 Jul 15 21:47:25 volumio volumio[14564]: 22: 0x558e5bed8184 - tokio_current_thread::Entered

::block_on::h885eb0739f27f74e Jul 15 21:47:25 volumio volumio[14564]: 23: 0x558e5befcf18 - std::thread::local::LocalKey::with::h221054beb46a2abc Jul 15 21:47:25 volumio volumio[14564]: 24: 0x558e5bf12250 - tokio_timer::clock::clock::with_default::h159e4adfb11c433f Jul 15 21:47:25 volumio volumio[14564]: 25: 0x558e5bed3104 - tokio::runtime::current_thread::runtime::Runtime::block_on::h7d24b175f2e44b17 Jul 15 21:47:25 volumio volumio[14564]: 26: 0x558e5becd3c9 - vollibrespot::main::hb13811fd4ab212f8 Jul 15 21:47:25 volumio volumio[14564]: 27: 0x558e5befc353 - std::sys_common::backtrace::__rust_begin_short_backtrace::h20ed102c66600ce2 Jul 15 21:47:25 volumio volumio[14564]: 28: 0x558e5befc859 - std::rt::lang_start::{{closure}}::h81eea2a75fc78b02 Jul 15 21:47:25 volumio volumio[14564]: 29: 0x558e5c3aaf9a - core::ops::function::impls:: for &F>::call_once::h44574effd2120c86 Jul 15 21:47:25 volumio volumio[14564]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/core/src/ops/function.rs:259:13 Jul 15 21:47:25 volumio volumio[14564]: 30: 0x558e5c3aaf9a - std::panicking::try::do_call::h10b0bd4879c8dfb0 Jul 15 21:47:25 volumio volumio[14564]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/panicking.rs:379:40 Jul 15 21:47:25 volumio volumio[14564]: 31: 0x558e5c3aaf9a - std::panicking::try::h60c6780d33419e92 Jul 15 21:47:25 volumio volumio[14564]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/panicking.rs:343:19 Jul 15 21:47:25 volumio volumio[14564]: 32: 0x558e5c3aaf9a - std::panic::catch_unwind::h111f33e08c52e2ce Jul 15 21:47:25 volumio volumio[14564]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/panic.rs:431:14 Jul 15 21:47:25 volumio volumio[14564]: 33: 0x558e5c3aaf9a - std::rt::lang_start_internal::h126f2e09345dbfda Jul 15 21:47:25 volumio volumio[14564]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/rt.rs:51:25 Jul 15 21:47:25 volumio volumio[14564]: 34: 0x558e5becea62 - main Jul 15 21:47:25 volumio volumio[14564]: 35: 0x7f1909aa709b - __libc_start_main Jul 15 21:47:25 volumio volumio[14564]: 36: 0x558e5bea228a - _start Jul 15 21:47:25 volumio volumio[14564]: 37: 0x0 - Jul 15 21:47:25 volumio volumio[14564]: [Vollibrespot] : Player thread panicked! Jul 15 21:47:25 volumio volumio[14564]: [Vollibrespot] : EventSender disconnected Jul 15 21:47:25 volumio volumio[747]: [SpotifyConnect] Device palyback is inactive Jul 15 21:47:25 volumio volumio[747]: [SpotifyConnect] Device Session is_active: true Jul 15 21:47:25 volumio volumio[747]: [SpotifyConnect] Sink released Jul 15 21:47:25 volumio volumio[747]: info: CoreCommandRouter::servicePushState Jul 15 21:47:25 volumio volumio[747]: info: CoreStateMachine::pushState Jul 15 21:47:25 volumio volumio[747]: info: CoreCommandRouter::volumioPushState Jul 15 21:47:25 volumio volumio[747]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 15 21:47:25 volumio systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=101/n/a Jul 15 21:47:25 volumio systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jul 15 21:47:27 volumio systemd[1]: volspotconnect2.service: Service RestartSec=2s expired, scheduling restart. Jul 15 21:47:27 volumio systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 1. Jul 15 21:47:27 volumio systemd[1]: Stopped Volspotconnect2 Daemon. Jul 15 21:47:27 volumio systemd[1]: Started Volspotconnect2 Daemon. Jul 15 21:47:27 volumio volumio[14607]: vollibrespot v0.2.3 769ce77 2021-06-04 (librespot 08d8bcc 2020-10-07) -- Built On 2021-06-04 Jul 15 21:47:27 volumio volumio[14607]: Reading Config from "volspotify.toml" Jul 15 21:47:27 volumio volumio[14607]: [Vollibrespot] : Using Alsa backend with device: volumio Jul 15 21:47:27 volumio volumio[14607]: [Vollibrespot] : Connecting to AP "gew1-accesspoint-a-tv99.ap.spotify.com:443" Jul 15 21:47:28 volumio volumio[14607]: [Vollibrespot] : Authenticated as "9iccyslfwi41tz7ti526wyoq6" ! Jul 15 21:47:28 volumio volumio[14607]: [Vollibrespot] : Setting up new mixer: card:hw:1 mixer:ARCAM Audio 2.0 Output index:0 Jul 15 21:47:28 volumio volumio[14607]: [Vollibrespot] : Alsa min-db is not SND_CTL_TLV_DB_GAIN_MUTE!! Jul 15 21:47:28 volumio volumio[14607]: [Vollibrespot] : Alsa Mixer info min: 0 (MilliBel(-12700)[dB]) -- max: 127 (MilliBel(0)[dB]) HW: true Jul 15 21:47:28 volumio volumio[14607]: [Vollibrespot] : Alsa min-db is not SND_CTL_TLV_DB_GAIN_MUTE!! Jul 15 21:47:28 volumio volumio[14607]: [Vollibrespot] : Using alsa sink Jul 15 21:47:28 volumio volumio[14607]: [Vollibrespot] : Country: "IN" Jul 15 21:47:28 volumio volumio[14607]: [Vollibrespot] : Metadata pipe established Jul 15 21:47:28 volumio volumio[14607]: [Vollibrespot] : Event: Volume { volume_to_mixer: 32767 } Jul 15 21:47:28 volumio volumio[747]: [SpotifyConnect] 49.999237048905165 Jul 15 21:47:28 volumio volumio[747]: [SpotifyConnect] Volume: Spotify:49.999237048905165 Volumio: 50 Jul 15 21:47:28 volumio volumio[747]: info: CoreStateMachine::pushState Jul 15 21:47:28 volumio volumio[747]: info: CoreCommandRouter::volumioPushState Jul 15 21:47:28 volumio volumio[747]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo ^Z [4]+ Stopped journalctl -f volumio@volumio:/data/plugins/audio_interface/fusiondsp$