::block_on::h339073902a399eb7 Apr 26 16:45:00 volumio volumio[8250]: 22: 0x534e00 - std::thread::local::LocalKey::with::hff64c13ccfec5327 Apr 26 16:45:00 volumio volumio[8250]: 23: 0x54120c - tokio_reactor::with_default::h7ef12e65cb103d2f Apr 26 16:45:00 volumio volumio[8250]: 24: 0x5149f8 - tokio::runtime::current_thread::runtime::Runtime::block_on::h043f136d6b8cf48f Apr 26 16:45:00 volumio volumio[8250]: 25: 0x55a2c8 - vollibrespot::main::h02a0d49d4cc5eaa6 Apr 26 16:45:00 volumio volumio[8250]: 26: 0x541f18 - std::rt::lang_start::{{closure}}::h279cd2b7929fdba7 Apr 26 16:45:00 volumio volumio[8250]: 27: 0xa3b404 - std::rt::lang_start_internal::{{closure}}::he93bfc404849b78a Apr 26 16:45:00 volumio volumio[8250]: at src/libstd/rt.rs:52 Apr 26 16:45:00 volumio volumio[8250]: 28: 0xa3b404 - std::panicking::try::do_call::h6e9e98f4078affb0 Apr 26 16:45:00 volumio volumio[8250]: at src/libstd/panicking.rs:348 Apr 26 16:45:00 volumio volumio[8250]: 29: 0xa3b404 - std::panicking::try::h2e68d4f7f799a6df Apr 26 16:45:00 volumio volumio[8250]: at src/libstd/panicking.rs:325 Apr 26 16:45:00 volumio volumio[8250]: 30: 0xa3b404 - std::panic::catch_unwind::h8880a4c07cc66391 Apr 26 16:45:00 volumio volumio[8250]: at src/libstd/panic.rs:394 Apr 26 16:45:00 volumio volumio[8250]: 31: 0xa3b404 - std::rt::lang_start_internal::hf4ae2140248bf16b Apr 26 16:45:00 volumio volumio[8250]: at src/libstd/rt.rs:51 Apr 26 16:45:00 volumio volumio[8250]: 32: 0x55ad84 - main Apr 26 16:45:00 volumio volumio[8250]: 33: 0x76c4e294 - __libc_start_main Apr 26 16:45:00 volumio volumio[8250]: [Vollibrespot] : EventSender disconnected Apr 26 16:45:00 volumio volumio[800]: [SpotifyConnect] Device palyback is inactive Apr 26 16:45:00 volumio volumio[800]: [SpotifyConnect] Device Session is_active: true Apr 26 16:45:00 volumio volumio[800]: [SpotifyConnect] Sink released Apr 26 16:45:00 volumio volumio[800]: info: CoreCommandRouter::servicePushState Apr 26 16:45:00 volumio volumio[800]: info: CoreStateMachine::pushState Apr 26 16:45:00 volumio volumio[800]: info: CoreCommandRouter::volumioPushState Apr 26 16:45:00 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 16:45:00 volumio systemd[1]: volspotconnect2.service: main process exited, code=exited, status=101/n/a Apr 26 16:45:00 volumio systemd[1]: Unit volspotconnect2.service entered failed state. Apr 26 16:45:02 volumio systemd[1]: volspotconnect2.service holdoff time over, scheduling restart. Apr 26 16:45:02 volumio systemd[1]: Stopping Volspotconnect2 Daemon... Apr 26 16:45:02 volumio systemd[1]: Starting Volspotconnect2 Daemon... Apr 26 16:45:02 volumio systemd[1]: Started Volspotconnect2 Daemon. Apr 26 16:45:02 volumio volumio[13308]: vollibrespot v0.2.2 cd96399 2020-10-07 (librespot 08d8bcc 2020-10-07) -- Built On 2020-10-07 Apr 26 16:45:02 volumio volumio[13308]: Reading Config from "volspotify.toml" Apr 26 16:45:02 volumio volumio[13308]: [Vollibrespot] : Using Alsa backend with device: plughw:2 Apr 26 16:45:02 volumio volumio[13308]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Other, message: "No such device" } Apr 26 16:45:09 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 26 16:45:09 volumio volumio[800]: info: CoreCommandRouter::volumioGetState Apr 26 16:45:09 volumio volumio[800]: info: CoreCommandRouter::volumioGetVisibleSources Apr 26 16:45:09 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 16:45:09 volumio volumio[13308]: [Vollibrespot] : couldn't parse packet from V4(192.168.50.183:5353): query type 65 is invalid Apr 26 16:45:10 volumio volumio[13308]: [Vollibrespot] : couldn't parse packet from V4(192.168.50.183:5353): query type 65 is invalid Apr 26 16:45:11 volumio volumio[800]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify Apr 26 16:45:11 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 16:45:11 volumio volumio[800]: [SpotifyConnect] Closing metadata listener Apr 26 16:45:11 volumio volumio[800]: info: Disabling plugin spop Apr 26 16:45:11 volumio volumio[800]: info: Done. Apr 26 16:45:11 volumio sudo[13329]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volspotconnect.service Apr 26 16:45:11 volumio sudo[13329]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 16:45:11 volumio systemd[1]: Stopping Volspotconnect2 Daemon... Apr 26 16:45:11 volumio systemd[1]: Stopped Volspotconnect2 Daemon. Apr 26 16:45:11 volumio sudo[13329]: pam_unix(sudo:session): session closed for user root Apr 26 16:45:11 volumio volumio[800]: [SpotifyConnect] Vollibrespot Daemon service stoped! Apr 26 16:45:12 volumio volumio[800]: [SpotifyConnect] Relinquishing Volumio State Apr 26 16:45:12 volumio volumio[800]: UNSET VOLATILE Apr 26 16:45:12 volumio volumio[800]: [SpotifyConnect] unSetVolatile called Apr 26 16:45:12 volumio volumio[800]: [SpotifyConnect] Relinquishing Volumio State to another service Apr 26 16:45:12 volumio volumio[800]: [SpotifyConnect] Received stop Apr 26 16:45:12 volumio volumio[800]: [SpotifyConnect] Stopping Vollibrespot daemon Apr 26 16:45:12 volumio volumio[800]: [SpotifyConnect] Closing metadata listener Apr 26 16:45:12 volumio volumio[800]: info: CoreStateMachine::resetVolumioState Apr 26 16:45:12 volumio volumio[800]: info: CoreStateMachine::getcurrentVolume Apr 26 16:45:12 volumio volumio[800]: info: CoreCommandRouter::volumioRetrievevolume Apr 26 16:45:12 volumio volumio[800]: info: Disabling plugin volspotconnect2 Apr 26 16:45:12 volumio volumio[800]: info: CoreStateMachine::pushState Apr 26 16:45:12 volumio volumio[800]: info: CorePlayQueue::getTrack 0 Apr 26 16:45:12 volumio volumio[800]: info: CoreCommandRouter::volumioPushState Apr 26 16:45:12 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 16:45:12 volumio volumio[800]: info: Done. Apr 26 16:45:12 volumio sudo[13341]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volspotconnect2.service Apr 26 16:45:12 volumio sudo[13341]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 16:45:12 volumio systemd[1]: Stopping Volspotconnect2 Daemon... Apr 26 16:45:12 volumio systemd[1]: Stopped Volspotconnect2 Daemon. Apr 26 16:45:12 volumio sudo[13341]: pam_unix(sudo:session): session closed for user root Apr 26 16:45:12 volumio volumio[800]: [SpotifyConnect] Vollibrespot Daemon service stoped! Apr 26 16:45:12 volumio volumio[800]: info: Enabling plugin volspotconnect2 Apr 26 16:45:12 volumio volumio[800]: info: Loading plugin "volspotconnect2"... Apr 26 16:45:12 volumio volumio[800]: info: PLUGIN START: volspotconnect2 Apr 26 16:45:12 volumio volumio[800]: [SpotifyConnect] Creating VLS config file Apr 26 16:45:12 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 16:45:12 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 16:45:12 volumio volumio[800]: [SpotifyConnect] <> or Mixer found, using softvol Apr 26 16:45:12 volumio volumio[800]: [SpotifyConnect] Starting metadata listener Apr 26 16:45:12 volumio sudo[13354]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service Apr 26 16:45:12 volumio sudo[13354]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 16:45:12 volumio systemd[1]: Starting Volspotconnect2 Daemon... Apr 26 16:45:12 volumio systemd[1]: Started Volspotconnect2 Daemon. Apr 26 16:45:12 volumio sudo[13354]: pam_unix(sudo:session): session closed for user root Apr 26 16:45:12 volumio volumio[13359]: vollibrespot v0.2.2 cd96399 2020-10-07 (librespot 08d8bcc 2020-10-07) -- Built On 2020-10-07 Apr 26 16:45:12 volumio volumio[13359]: Reading Config from "volspotify.toml" Apr 26 16:45:12 volumio volumio[13359]: [Vollibrespot] : Using Alsa backend with device: plughw:2 Apr 26 16:45:12 volumio volumio[800]: [SpotifyConnect] Vollibrespot Daemon service started! Apr 26 16:45:12 volumio volumio[800]: [Metrics] SpotifyConnect: 0s 139.71ms Apr 26 16:45:12 volumio volumio[800]: info: Done. Apr 26 16:45:13 volumio volumio[13359]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Other, message: "No such device" } Apr 26 16:45:13 volumio volumio[800]: info: Enabling plugin spop Apr 26 16:45:13 volumio volumio[800]: info: Loading plugin "spop"... Apr 26 16:45:13 volumio volumio[800]: info: PLUGIN START: spop Apr 26 16:45:13 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 26 16:45:13 volumio volumio[800]: [SpotifyConnect] Creating VLS config file Apr 26 16:45:13 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 16:45:13 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 16:45:13 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 16:45:13 volumio volumio[800]: [SpotifyConnect] SpotifyConnect Daemon config issues! Apr 26 16:45:13 volumio volumio[800]: [SpotifyConnect] volspotify config error: [ '#initial-volume = undefined', [length]: 1 ] Apr 26 16:45:13 volumio volumio[800]: [SpotifyConnect] Starting metadata listener Apr 26 16:45:13 volumio sudo[13365]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hosts Apr 26 16:45:13 volumio volumio[800]: [SpotifyConnect] Error: bind EADDRINUSE 0.0.0.0:5030 Apr 26 16:45:13 volumio volumio[800]: at Object._errnoException (util.js:1022:11) Apr 26 16:45:13 volumio volumio[800]: at _exceptionWithHostPort (util.js:1044:20) Apr 26 16:45:13 volumio volumio[800]: at _handle.lookup (dgram.js:266:18) Apr 26 16:45:13 volumio volumio[800]: at _combinedTickCallback (internal/process/next_tick.js:141:11) Apr 26 16:45:13 volumio volumio[800]: at process._tickCallback (internal/process/next_tick.js:180:9) Apr 26 16:45:13 volumio sudo[13365]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 16:45:13 volumio volumio[800]: [SpotifyConnect] Error [ERR_SOCKET_CANNOT_SEND]: Unable to send data Apr 26 16:45:13 volumio volumio[800]: at Socket.onListenError (dgram.js:362:22) Apr 26 16:45:13 volumio volumio[800]: at Object.onceWrapper (events.js:315:30) Apr 26 16:45:13 volumio volumio[800]: at emitOne (events.js:121:20) Apr 26 16:45:13 volumio volumio[800]: at Socket.emit (events.js:211:7) Apr 26 16:45:13 volumio volumio[800]: at _handle.lookup (dgram.js:267:14) Apr 26 16:45:13 volumio volumio[800]: at _combinedTickCallback (internal/process/next_tick.js:141:11) Apr 26 16:45:13 volumio volumio[800]: at process._tickCallback (internal/process/next_tick.js:180:9) Apr 26 16:45:13 volumio sudo[13365]: pam_unix(sudo:session): session closed for user root Apr 26 16:45:13 volumio sudo[13369]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service Apr 26 16:45:13 volumio sudo[13369]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 16:45:13 volumio systemd[1]: Starting Volspotconnect2 Daemon... Apr 26 16:45:13 volumio systemd[1]: Started Volspotconnect2 Daemon. Apr 26 16:45:13 volumio sudo[13369]: pam_unix(sudo:session): session closed for user root Apr 26 16:45:13 volumio volumio[13380]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-07-11 Apr 26 16:45:13 volumio volumio[13380]: Reading Config from "/tmp/volspotify.toml" Apr 26 16:45:13 volumio volumio[13380]: [Vollibrespot] : Using Alsa backend with device: plughw:2 Apr 26 16:45:13 volumio volumio[800]: [SpotifyConnect] Vollibrespot Daemon service started! Apr 26 16:45:13 volumio volumio[800]: [Metrics] SpotifyConnect: 0s 186.44ms Apr 26 16:45:13 volumio volumio[800]: info: Done. Apr 26 16:45:13 volumio volumio[13380]: [Vollibrespot] : Connecting to AP "ap-gew4.spotify.com:443" Apr 26 16:45:14 volumio volumio[13380]: thread 'main' panicked at 'Authentication failed with reason: BadCredentials', /home/volumio/.cargo/git/checkouts/librespot-6f197fd632ef9380/08d8bcc/core/src/connection/mod.rs:93:21 Apr 26 16:45:14 volumio volumio[13380]: stack backtrace: Apr 26 16:45:14 volumio volumio[13380]: 0: 0x97ec40 - std::backtrace_rs::backtrace::libunwind::trace::h9be44abcd6023537 Apr 26 16:45:14 volumio volumio[13380]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/../../backtrace/src/backtrace/libunwind.rs:93:5 Apr 26 16:45:14 volumio volumio[13380]: 1: 0x97ec40 - std::backtrace_rs::backtrace::trace_unsynchronized::he588770997e640ec Apr 26 16:45:14 volumio volumio[13380]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 Apr 26 16:45:14 volumio volumio[13380]: 2: 0x97ec40 - std::sys_common::backtrace::_print_fmt::h2a5bea2fa6ce9b3d Apr 26 16:45:14 volumio volumio[13380]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/sys_common/backtrace.rs:66:5 Apr 26 16:45:14 volumio volumio[13380]: 3: 0x97ec40 - ::fmt::hf1c91d83d30a63b5 Apr 26 16:45:14 volumio volumio[13380]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/sys_common/backtrace.rs:45:22 Apr 26 16:45:14 volumio volumio[13380]: 4: 0x9a4d68 - core::fmt::write::he7b81f119cac9e82 Apr 26 16:45:14 volumio volumio[13380]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/core/src/fmt/mod.rs:1196:17 Apr 26 16:45:14 volumio volumio[13380]: 5: 0x9785d4 - std::io::Write::write_fmt::hd6a547671801193b Apr 26 16:45:14 volumio volumio[13380]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/io/mod.rs:1654:15 Apr 26 16:45:14 volumio volumio[13380]: 6: 0x980728 - std::sys_common::backtrace::_print::hcf4a7819f0b4198f Apr 26 16:45:14 volumio volumio[13380]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/sys_common/backtrace.rs:48:5 Apr 26 16:45:14 volumio volumio[13380]: 7: 0x980728 - std::sys_common::backtrace::print::hb4deb0b578e6c939 Apr 26 16:45:14 volumio volumio[13380]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/sys_common/backtrace.rs:35:9 Apr 26 16:45:14 volumio volumio[13380]: 8: 0x980728 - std::panicking::default_hook::{{closure}}::h7d65a85d73ca528f Apr 26 16:45:14 volumio volumio[13380]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:295:22 Apr 26 16:45:14 volumio volumio[13380]: 9: 0x98023c - std::panicking::default_hook::h3b931251a9c4b1f6 Apr 26 16:45:14 volumio volumio[13380]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:314:9 Apr 26 16:45:14 volumio volumio[13380]: 10: 0x980f44 - std::panicking::rust_panic_with_hook::h52cc4877d8c74a8b Apr 26 16:45:14 volumio volumio[13380]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:698:17 Apr 26 16:45:14 volumio volumio[13380]: 11: 0x980c30 - std::panicking::begin_panic_handler::{{closure}}::h7546eed0ba906ec0 Apr 26 16:45:14 volumio volumio[13380]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:588:13 Apr 26 16:45:14 volumio volumio[13380]: 12: 0x97f1bc - std::sys_common::backtrace::__rust_end_short_backtrace::h32ad68afde8ac292 Apr 26 16:45:14 volumio volumio[13380]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/sys_common/backtrace.rs:138:18 Apr 26 16:45:14 volumio volumio[13380]: 13: 0x9809ec - rust_begin_unwind Apr 26 16:45:14 volumio volumio[13380]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:584:5 Apr 26 16:45:14 volumio volumio[13380]: 14: 0x463e2c - core::panicking::panic_fmt::h43e2baa6a2dcb05a Apr 26 16:45:14 volumio volumio[13380]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/core/src/panicking.rs:142:14 Apr 26 16:45:14 volumio volumio[13380]: 15: 0x640538 - futures::future::chain::Chain::poll::hccd4c598af3a6335 Apr 26 16:45:14 volumio volumio[13380]: 16: 0x65aaf4 - as futures::future::Future>::poll::h38eb93796db62721 Apr 26 16:45:14 volumio volumio[13380]: 17: 0x63cba8 - futures::future::chain::Chain::poll::h39f31221e88b3bd1 Apr 26 16:45:14 volumio volumio[13380]: 18: 0x6c6fc0 - as futures::future::Future>::poll::hc585bb28ee7b8420 Apr 26 16:45:14 volumio volumio[13380]: 19: 0x48cdd8 - ::poll::h06f390c1cf340695 Apr 26 16:45:14 volumio volumio[13380]: 20: 0x4a418c - futures::task_impl::std::set::h917345edbb2d7b17 Apr 26 16:45:14 volumio volumio[13380]: 21: 0x4c5f24 - std::thread::local::LocalKey::with::hebf033a3f9f19674 Apr 26 16:45:14 volumio volumio[13380]: 22: 0x494924 - tokio_current_thread::Entered