-- Logs begin at Sun 2025-05-18 11:37:52 CEST, end at Sun 2025-05-18 11:42:18 CEST. -- May 18 11:41:04 volumio volumio-time-update[665]: volumio-time-update-util: Fetching time from Volumio... May 18 11:41:21 volumio ntpd[1914]: error resolving pool 3.debian.pool.ntp.org: System error (-11) May 18 11:41:22 volumio volumio[921]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 May 18 11:41:22 volumio volumio[921]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 May 18 11:41:29 volumio volumio[2168]: thread 'main' panicked at 'called `Result::unwrap()` on an `Err` value: Os { code: 16, kind: ResourceBusy, message: "Device or resource busy" }', /build/cache/git/checkouts/librespot-6f197fd632ef9380/08d8bcc/core/src/connection/mod.rs:33:41 May 18 11:41:29 volumio volumio[2168]: stack backtrace: May 18 11:41:29 volumio volumio[2168]: 0: 0x558ce7b280cd - std::backtrace_rs::backtrace::libunwind::trace::hee598835bc88d35b May 18 11:41:29 volumio volumio[2168]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/../../backtrace/src/backtrace/libunwind.rs:93:5 May 18 11:41:29 volumio volumio[2168]: 1: 0x558ce7b280cd - std::backtrace_rs::backtrace::trace_unsynchronized::h9cdc730ba5cf5d72 May 18 11:41:29 volumio volumio[2168]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 May 18 11:41:29 volumio volumio[2168]: 2: 0x558ce7b280cd - std::sys_common::backtrace::_print_fmt::h75aeaf7ed30e43fa May 18 11:41:29 volumio volumio[2168]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:66:5 May 18 11:41:29 volumio volumio[2168]: 3: 0x558ce7b280cd - ::fmt::h606862f787600875 May 18 11:41:29 volumio volumio[2168]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:45:22 May 18 11:41:29 volumio volumio[2168]: 4: 0x558ce7b4df5c - core::fmt::write::he803f0f418caf762 May 18 11:41:29 volumio volumio[2168]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/fmt/mod.rs:1190:17 May 18 11:41:29 volumio volumio[2168]: 5: 0x558ce7b20cb8 - std::io::Write::write_fmt::h70bc45872f37e7bb May 18 11:41:29 volumio volumio[2168]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/io/mod.rs:1657:15 May 18 11:41:29 volumio volumio[2168]: 6: 0x558ce7b2a5a7 - std::sys_common::backtrace::_print::h64d038cf8ac3e13e May 18 11:41:29 volumio volumio[2168]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:48:5 May 18 11:41:29 volumio volumio[2168]: 7: 0x558ce7b2a5a7 - std::sys_common::backtrace::print::h359300b4a7fccf65 May 18 11:41:29 volumio volumio[2168]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:35:9 May 18 11:41:29 volumio volumio[2168]: 8: 0x558ce7b2a5a7 - std::panicking::default_hook::{{closure}}::hf51be35e2f510149 May 18 11:41:29 volumio volumio[2168]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:295:22 May 18 11:41:29 volumio volumio[2168]: 9: 0x558ce7b2a270 - std::panicking::default_hook::h03ca0f22e1d2d25e May 18 11:41:29 volumio volumio[2168]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:314:9 May 18 11:41:29 volumio volumio[2168]: 10: 0x558ce7b2acf9 - std::panicking::rust_panic_with_hook::h3b7380e99b825b63 May 18 11:41:29 volumio volumio[2168]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:698:17 May 18 11:41:29 volumio volumio[2168]: 11: 0x558ce7b2a9e7 - std::panicking::begin_panic_handler::{{closure}}::h8e849d0710154ce0 May 18 11:41:29 volumio volumio[2168]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:588:13 May 18 11:41:29 volumio volumio[2168]: 12: 0x558ce7b28594 - std::sys_common::backtrace::__rust_end_short_backtrace::hedcdaddbd4c46cc5 May 18 11:41:29 volumio volumio[2168]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:138:18 May 18 11:41:29 volumio volumio[2168]: 13: 0x558ce7b2a6f9 - rust_begin_unwind May 18 11:41:29 volumio volumio[2168]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:584:5 May 18 11:41:29 volumio volumio[2168]: 14: 0x558ce7633f53 - core::panicking::panic_fmt::he1bbc7336d49a357 May 18 11:41:29 volumio volumio[2168]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/panicking.rs:143:14 May 18 11:41:29 volumio volumio[2168]: 15: 0x558ce7634043 - core::result::unwrap_failed::h719f957ed74f93fd May 18 11:41:29 volumio volumio[2168]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/result.rs:1749:5 May 18 11:41:29 volumio volumio[2168]: 16: 0x558ce7879c8b - librespot_core::connection::connect::hec062bc3b8ab8d24 May 18 11:41:29 volumio volumio[2168]: 17: 0x558ce77fdf24 - futures::future::chain::Chain::poll::h608ebf2915eb2058 May 18 11:41:29 volumio volumio[2168]: 18: 0x558ce78028fc - futures::future::chain::Chain::poll::hda5263cfb178296b May 18 11:41:29 volumio volumio[2168]: 19: 0x558ce7877fe1 - as futures::future::Future>::poll::h7445370ea1c2ee99 May 18 11:41:29 volumio volumio[2168]: 20: 0x558ce7661736 - ::poll::hdecdb1a04917de4b May 18 11:41:29 volumio volumio[2168]: 21: 0x558ce7679149 - futures::task_impl::std::set::h6dd4b73f744a06d4 May 18 11:41:29 volumio volumio[2168]: 22: 0x558ce768f3bd - std::thread::local::LocalKey::with::hc9bf2e930c1fdc36 May 18 11:41:29 volumio volumio[2168]: 23: 0x558ce7675c56 - tokio_current_thread::Entered

::block_on::h372d7b837fe25d26 May 18 11:41:29 volumio volumio[2168]: 24: 0x558ce768eb48 - std::thread::local::LocalKey::with::h247079172a6ad00b May 18 11:41:29 volumio volumio[2168]: 25: 0x558ce76788c5 - tokio::runtime::current_thread::runtime::Runtime::block_on::h538e89536cb974b3 May 18 11:41:29 volumio volumio[2168]: 26: 0x558ce76637e9 - vollibrespot::main::hf67292b633164777 May 18 11:41:29 volumio volumio[2168]: 27: 0x558ce767dc73 - std::sys_common::backtrace::__rust_begin_short_backtrace::h9396fe1ba8d4dd7f May 18 11:41:29 volumio volumio[2168]: 28: 0x558ce76a0ce9 - std::rt::lang_start::{{closure}}::h0862538761d46dbc May 18 11:41:29 volumio volumio[2168]: 29: 0x558ce7b277b1 - core::ops::function::impls:: for &F>::call_once::hb7014f43484a8b4e May 18 11:41:29 volumio volumio[2168]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/ops/function.rs:259:13 May 18 11:41:29 volumio volumio[2168]: 30: 0x558ce7b277b1 - std::panicking::try::do_call::h7bc9dc436daeb8c7 May 18 11:41:29 volumio volumio[2168]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:492:40 May 18 11:41:29 volumio volumio[2168]: 31: 0x558ce7b277b1 - std::panicking::try::h653d68a27ff5f175 May 18 11:41:29 volumio volumio[2168]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:456:19 May 18 11:41:29 volumio volumio[2168]: 32: 0x558ce7b277b1 - std::panic::catch_unwind::h9d739f9f59895e68 May 18 11:41:29 volumio volumio[2168]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panic.rs:137:14 May 18 11:41:29 volumio volumio[2168]: 33: 0x558ce7b277b1 - std::rt::lang_start_internal::{{closure}}::hf006f2bc7ce22bbe May 18 11:41:29 volumio volumio[2168]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/rt.rs:128:48 May 18 11:41:29 volumio volumio[2168]: 34: 0x558ce7b277b1 - std::panicking::try::do_call::hfb39d6df61a2e69f May 18 11:41:29 volumio volumio[2168]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:492:40 May 18 11:41:29 volumio volumio[2168]: 35: 0x558ce7b277b1 - std::panicking::try::h13e2d225134958ac May 18 11:41:29 volumio volumio[2168]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:456:19 May 18 11:41:29 volumio volumio[2168]: 36: 0x558ce7b277b1 - std::panic::catch_unwind::h3bd49b5a5dfb1a50 May 18 11:41:29 volumio volumio[2168]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panic.rs:137:14 May 18 11:41:29 volumio volumio[2168]: 37: 0x558ce7b277b1 - std::rt::lang_start_internal::h2ba92edce36c035e May 18 11:41:29 volumio volumio[2168]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/rt.rs:128:20 May 18 11:41:29 volumio volumio[2168]: 38: 0x558ce7666562 - main May 18 11:41:29 volumio volumio[2168]: 39: 0x7fa007c2509b - __libc_start_main May 18 11:41:29 volumio volumio[2168]: 40: 0x558ce763478a - _start May 18 11:41:29 volumio volumio[2168]: 41: 0x0 - May 18 11:41:29 volumio systemd[1]: volspotconnect.service: Main process exited, code=exited, status=101/n/a May 18 11:41:29 volumio systemd[1]: volspotconnect.service: Failed with result 'exit-code'. May 18 11:41:31 volumio systemd[1]: volspotconnect.service: Service RestartSec=2s expired, scheduling restart. May 18 11:41:31 volumio systemd[1]: volspotconnect.service: Scheduled restart job, restart counter is at 4. May 18 11:41:31 volumio systemd[1]: Stopped Volspotconnect2 Daemon. May 18 11:41:31 volumio systemd[1]: Started Volspotconnect2 Daemon. May 18 11:41:31 volumio volumio[2355]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 May 18 11:41:31 volumio volumio[2355]: Reading Config from "/tmp/volspotify.toml" May 18 11:41:31 volumio volumio[2355]: [Vollibrespot] : Using Alsa backend with device: volumio May 18 11:41:31 volumio volumio[2355]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" } May 18 11:41:41 volumio volumio-time-update[665]: volumio-time-update-util: Date not found in response May 18 11:41:41 volumio volumio-time-update[665]: volumio-time-update-util: Retrying in 5 seconds... May 18 11:41:46 volumio volumio-time-update[665]: volumio-time-update-util: Fetching time from Volumio... May 18 11:41:48 volumio volumio[2355]: [Vollibrespot] : couldn't parse packet from 192.168.1.156:5353: type 47 is invalid May 18 11:41:57 volumio ntpd[1914]: error resolving pool 0.debian.pool.ntp.org: System error (-11) May 18 11:42:06 volumio ntpd[1914]: Soliciting pool server 81.56.230.156 May 18 11:42:06 volumio ntpd[1914]: Soliciting pool server 212.45.144.3 May 18 11:42:06 volumio ntpd[1914]: Soliciting pool server 185.157.229.254 May 18 11:42:06 volumio ntpd[1914]: Soliciting pool server 31.14.133.122 May 18 11:42:06 volumio ntpd[1914]: Soliciting pool server 212.6.50.243 May 18 11:42:06 volumio ntpd[1914]: Soliciting pool server 93.94.88.50 May 18 11:42:06 volumio ntpd[1914]: Soliciting pool server 37.247.53.178 May 18 11:42:07 volumio volumio[921]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 May 18 11:42:07 volumio volumio[921]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 May 18 11:42:07 volumio volumio[921]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 May 18 11:42:07 volumio volumio[921]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 May 18 11:42:07 volumio volumio[921]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 May 18 11:42:07 volumio volumio[921]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 May 18 11:42:07 volumio volumio[921]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 May 18 11:42:07 volumio volumio[921]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 May 18 11:42:07 volumio volumio[921]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 May 18 11:42:07 volumio volumio[921]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 May 18 11:42:07 volumio volumio[921]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 May 18 11:42:07 volumio volumio[921]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 May 18 11:42:07 volumio volumio[921]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 15 May 18 11:42:07 volumio volumio[921]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 16 May 18 11:42:07 volumio volumio[2355]: [Vollibrespot] : Failed to resolve Access Point: HTTP error May 18 11:42:07 volumio volumio[2355]: [Vollibrespot] : Using fallback "ap.spotify.com:443" May 18 11:42:07 volumio volumio[2355]: [Vollibrespot] : Connecting to AP "ap.spotify.com:443" May 18 11:42:07 volumio volumio[2355]: thread 'main' panicked at 'Authentication failed with reason: BadCredentials', /build/cache/git/checkouts/librespot-6f197fd632ef9380/08d8bcc/core/src/connection/mod.rs:93:21 May 18 11:42:07 volumio volumio[2355]: stack backtrace: May 18 11:42:07 volumio volumio[2355]: 0: 0x55b500f850cd - std::backtrace_rs::backtrace::libunwind::trace::hee598835bc88d35b May 18 11:42:07 volumio volumio[2355]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/../../backtrace/src/backtrace/libunwind.rs:93:5 May 18 11:42:07 volumio volumio[2355]: 1: 0x55b500f850cd - std::backtrace_rs::backtrace::trace_unsynchronized::h9cdc730ba5cf5d72 May 18 11:42:07 volumio volumio[2355]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 May 18 11:42:07 volumio volumio[2355]: 2: 0x55b500f850cd - std::sys_common::backtrace::_print_fmt::h75aeaf7ed30e43fa May 18 11:42:07 volumio volumio[2355]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:66:5 May 18 11:42:07 volumio volumio[2355]: 3: 0x55b500f850cd - ::fmt::h606862f787600875 May 18 11:42:07 volumio volumio[2355]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:45:22 May 18 11:42:07 volumio volumio[2355]: 4: 0x55b500faaf5c - core::fmt::write::he803f0f418caf762 May 18 11:42:07 volumio volumio[2355]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/fmt/mod.rs:1190:17 May 18 11:42:07 volumio volumio[2355]: 5: 0x55b500f7dcb8 - std::io::Write::write_fmt::h70bc45872f37e7bb May 18 11:42:07 volumio volumio[2355]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/io/mod.rs:1657:15 May 18 11:42:07 volumio volumio[2355]: 6: 0x55b500f875a7 - std::sys_common::backtrace::_print::h64d038cf8ac3e13e May 18 11:42:07 volumio volumio[2355]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:48:5 May 18 11:42:07 volumio volumio[2355]: 7: 0x55b500f875a7 - std::sys_common::backtrace::print::h359300b4a7fccf65 May 18 11:42:07 volumio volumio[2355]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:35:9 May 18 11:42:07 volumio volumio[2355]: 8: 0x55b500f875a7 - std::panicking::default_hook::{{closure}}::hf51be35e2f510149 May 18 11:42:07 volumio volumio[2355]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:295:22 May 18 11:42:07 volumio volumio[2355]: 9: 0x55b500f87270 - std::panicking::default_hook::h03ca0f22e1d2d25e May 18 11:42:07 volumio volumio[2355]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:314:9 May 18 11:42:07 volumio volumio[2355]: 10: 0x55b500f87cf9 - std::panicking::rust_panic_with_hook::h3b7380e99b825b63 May 18 11:42:07 volumio volumio[2355]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:698:17 May 18 11:42:07 volumio volumio[2355]: 11: 0x55b500f879e7 - std::panicking::begin_panic_handler::{{closure}}::h8e849d0710154ce0 May 18 11:42:07 volumio volumio[2355]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:588:13 May 18 11:42:07 volumio volumio[2355]: 12: 0x55b500f85594 - std::sys_common::backtrace::__rust_end_short_backtrace::hedcdaddbd4c46cc5 May 18 11:42:07 volumio volumio[2355]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:138:18 May 18 11:42:07 volumio volumio[2355]: 13: 0x55b500f876f9 - rust_begin_unwind May 18 11:42:07 volumio volumio[2355]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:584:5 May 18 11:42:07 volumio volumio[2355]: 14: 0x55b500a90f53 - core::panicking::panic_fmt::he1bbc7336d49a357 May 18 11:42:07 volumio volumio[2355]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/panicking.rs:143:14 May 18 11:42:07 volumio volumio[2355]: 15: 0x55b500c60f56 - futures::future::chain::Chain::poll::hf0e729e3d4d8df0d May 18 11:42:07 volumio volumio[2355]: 16: 0x55b500cd3a19 - as futures::future::Future>::poll::hb2bebc352335cc1a May 18 11:42:07 volumio volumio[2355]: 17: 0x55b500c5f8e6 - futures::future::chain::Chain::poll::hda5263cfb178296b May 18 11:42:07 volumio volumio[2355]: 18: 0x55b500cd4fe1 - as futures::future::Future>::poll::h7445370ea1c2ee99 May 18 11:42:07 volumio volumio[2355]: 19: 0x55b500abe736 - ::poll::hdecdb1a04917de4b May 18 11:42:07 volumio volumio[2355]: 20: 0x55b500ad6149 - futures::task_impl::std::set::h6dd4b73f744a06d4 May 18 11:42:07 volumio volumio[2355]: 21: 0x55b500aec3bd - std::thread::local::LocalKey::with::hc9bf2e930c1fdc36 May 18 11:42:07 volumio volumio[2355]: 22: 0x55b500ad2c56 - tokio_current_thread::Entered

::block_on::h372d7b837fe25d26 May 18 11:42:07 volumio volumio[2355]: 23: 0x55b500aebb48 - std::thread::local::LocalKey::with::h247079172a6ad00b May 18 11:42:07 volumio volumio[2355]: 24: 0x55b500ad58c5 - tokio::runtime::current_thread::runtime::Runtime::block_on::h538e89536cb974b3 May 18 11:42:07 volumio volumio[2355]: 25: 0x55b500ac07e9 - vollibrespot::main::hf67292b633164777 May 18 11:42:07 volumio volumio[2355]: 26: 0x55b500adac73 - std::sys_common::backtrace::__rust_begin_short_backtrace::h9396fe1ba8d4dd7f May 18 11:42:07 volumio volumio[2355]: 27: 0x55b500afdce9 - std::rt::lang_start::{{closure}}::h0862538761d46dbc May 18 11:42:07 volumio volumio[2355]: 28: 0x55b500f847b1 - core::ops::function::impls:: for &F>::call_once::hb7014f43484a8b4e May 18 11:42:07 volumio volumio[2355]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/ops/function.rs:259:13 May 18 11:42:07 volumio volumio[2355]: 29: 0x55b500f847b1 - std::panicking::try::do_call::h7bc9dc436daeb8c7 May 18 11:42:07 volumio volumio[2355]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:492:40 May 18 11:42:07 volumio volumio[2355]: 30: 0x55b500f847b1 - std::panicking::try::h653d68a27ff5f175 May 18 11:42:07 volumio volumio[2355]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:456:19 May 18 11:42:07 volumio volumio[2355]: 31: 0x55b500f847b1 - std::panic::catch_unwind::h9d739f9f59895e68 May 18 11:42:07 volumio volumio[2355]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panic.rs:137:14 May 18 11:42:07 volumio volumio[2355]: 32: 0x55b500f847b1 - std::rt::lang_start_internal::{{closure}}::hf006f2bc7ce22bbe May 18 11:42:07 volumio volumio[2355]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/rt.rs:128:48 May 18 11:42:07 volumio volumio[2355]: 33: 0x55b500f847b1 - std::panicking::try::do_call::hfb39d6df61a2e69f May 18 11:42:07 volumio volumio[2355]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:492:40 May 18 11:42:07 volumio volumio[2355]: 34: 0x55b500f847b1 - std::panicking::try::h13e2d225134958ac May 18 11:42:07 volumio volumio[2355]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:456:19 May 18 11:42:07 volumio volumio[2355]: 35: 0x55b500f847b1 - std::panic::catch_unwind::h3bd49b5a5dfb1a50 May 18 11:42:07 volumio volumio[2355]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panic.rs:137:14 May 18 11:42:07 volumio volumio[2355]: 36: 0x55b500f847b1 - std::rt::lang_start_internal::h2ba92edce36c035e May 18 11:42:07 volumio volumio[2355]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/rt.rs:128:20 May 18 11:42:07 volumio volumio[2355]: 37: 0x55b500ac3562 - main May 18 11:42:07 volumio volumio[2355]: 38: 0x7f553081f09b - __libc_start_main May 18 11:42:07 volumio volumio[2355]: 39: 0x55b500a9178a - _start May 18 11:42:07 volumio volumio[2355]: 40: 0x0 - May 18 11:42:07 volumio systemd[1]: volspotconnect.service: Main process exited, code=exited, status=101/n/a May 18 11:42:07 volumio systemd[1]: volspotconnect.service: Failed with result 'exit-code'. May 18 11:42:08 volumio volumio[921]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 18 11:42:08 volumio volumio[921]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 18 11:42:08 volumio volumio[921]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 18 11:42:08 volumio volumio[921]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 18 11:42:08 volumio volumio[921]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 18 11:42:08 volumio volumio[921]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 18 11:42:08 volumio volumio[921]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 18 11:42:08 volumio volumio[921]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 18 11:42:08 volumio volumio[921]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 18 11:42:08 volumio volumio[921]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 18 11:42:08 volumio volumio[921]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium May 18 11:42:08 volumio volumio[921]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 18 11:42:08 volumio volumio[921]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 18 11:42:08 volumio volumio[921]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 18 11:42:08 volumio volumio[921]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 18 11:42:08 volumio volumio[921]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 18 11:42:08 volumio volumio[921]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 18 11:42:08 volumio volumio[921]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 18 11:42:08 volumio volumio[921]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 18 11:42:08 volumio volumio[921]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 18 11:42:08 volumio volumio[921]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 18 11:42:08 volumio volumio[921]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar May 18 11:42:08 volumio volumio[921]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 18 11:42:08 volumio volumio[921]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 18 11:42:08 volumio volumio[921]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 18 11:42:08 volumio volumio[921]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 18 11:42:08 volumio volumio[921]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 18 11:42:08 volumio volumio[921]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 18 11:42:08 volumio volumio[921]: info: Adding plugin bluetooth to MyMusic Plugins May 18 11:42:08 volumio volumio[921]: info: Adding plugin multiroom to MyMusic Plugins May 18 11:42:08 volumio volumio[921]: info: Adding plugin metavolumio to MyMusic Plugins May 18 11:42:08 volumio volumio[921]: info: Adding plugin cd_controller to MyMusic Plugins May 18 11:42:08 volumio volumio[921]: info: Adding plugin smart_inputs to MyMusic Plugins May 18 11:42:08 volumio volumio[921]: info: Adding plugin tidalconnect to MyMusic Plugins May 18 11:42:08 volumio volumio[921]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 18 11:42:09 volumio volumio[921]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 18 11:42:09 volumio volumio[921]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 18 11:42:09 volumio volumio[921]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 11:42:09 volumio volumio[921]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 11:42:09 volumio volumio[921]: info: Starting MyVolumio Remote Streaming Endpoints May 18 11:42:09 volumio volumio[921]: info: MyVolumio login type: Token May 18 11:42:09 volumio volumio[921]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 18 11:42:09 volumio volumio[921]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... May 18 11:42:09 volumio systemd[1]: volspotconnect.service: Service RestartSec=2s expired, scheduling restart. May 18 11:42:09 volumio systemd[1]: volspotconnect.service: Scheduled restart job, restart counter is at 5. May 18 11:42:09 volumio systemd[1]: Stopped Volspotconnect2 Daemon. May 18 11:42:10 volumio systemd[1]: Started Volspotconnect2 Daemon. May 18 11:42:10 volumio volumio[2474]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 May 18 11:42:10 volumio volumio[2474]: Reading Config from "/tmp/volspotify.toml" May 18 11:42:10 volumio volumio[2474]: [Vollibrespot] : Using Alsa backend with device: volumio May 18 11:42:10 volumio volumio[2474]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" } May 18 11:42:10 volumio volumio[2474]: [Vollibrespot] : Connecting to AP "ap-gew4.spotify.com:443" May 18 11:42:10 volumio volumio[921]: info: Starting Streaming Service Transparent Proxy May 18 11:42:10 volumio volumio[921]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 18 11:42:10 volumio volumio[921]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 18 11:42:10 volumio volumio[921]: info: Streaming services startup May 18 11:42:10 volumio volumio[921]: info: Starting Streaming Daemon May 18 11:42:10 volumio sudo[2488]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 18 11:42:10 volumio sudo[2488]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 11:42:10 volumio sudo[2488]: pam_unix(sudo:session): session closed for user root May 18 11:42:10 volumio volumio[921]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started May 18 11:42:10 volumio volumio[921]: error: Cannot start Volumio Streaming Daemon May 18 11:42:10 volumio volumio[921]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 18 11:42:10 volumio volumio[921]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 18 11:42:10 volumio volumio[921]: error: MyVolumio Custom Token format not valid, refreshing it May 18 11:42:11 volumio volumio[921]: STREAMING PROXY: Starting server on port 3245 May 18 11:42:11 volumio volumio[921]: Node JS runtime: 14 May 18 11:42:11 volumio volumio[921]: info: MyVolumio login type: Token May 18 11:42:12 volumio volumio[921]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 18 11:42:12 volumio volumio[921]: info: MyVolumio token set successfully May 18 11:42:12 volumio volumio[921]: info: MYVOLUMIO: Adding device May 18 11:42:12 volumio volumio[921]: info: MYVOLUMIO: Evaluating Server May 18 11:42:13 volumio volumio-time-update[665]: volumio-time-update-util: Date not found in response May 18 11:42:13 volumio volumio-time-update[665]: volumio-time-update-util: Retrying in 5 seconds... May 18 11:42:13 volumio volumio[921]: info: MyVolumio status changed May 18 11:42:13 volumio volumio[921]: info: Streaming services startup May 18 11:42:13 volumio volumio[921]: info: Starting Streaming Daemon May 18 11:42:13 volumio ntpd[1914]: Soliciting pool server 185.19.184.35 May 18 11:42:13 volumio ntpd[1914]: Soliciting pool server 212.45.144.88 May 18 11:42:13 volumio volumio[921]: info: Removing browser output: myVolumio user plan is not superstar May 18 11:42:13 volumio volumio[921]: info: Removing audio output: May 18 11:42:13 volumio volumio[921]: info: Stoppping Tunnel 1 May 18 11:42:13 volumio sudo[2525]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 18 11:42:13 volumio sudo[2525]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 11:42:13 volumio sudo[2525]: pam_unix(sudo:session): session closed for user root May 18 11:42:13 volumio sudo[2528]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service May 18 11:42:13 volumio sudo[2528]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 11:42:13 volumio volumio[921]: error: Cannot start Volumio Streaming Daemon May 18 11:42:13 volumio volumio[921]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 18 11:42:13 volumio volumio[921]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 18 11:42:13 volumio sudo[2528]: pam_unix(sudo:session): session closed for user root May 18 11:42:13 volumio volumio[921]: info: Remote SSH Stopped May 18 11:42:13 volumio volumio[921]: info: Setting Geolocation for MyVolumio to eu10 May 18 11:42:13 volumio volumio[921]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 11:42:13 volumio volumio[921]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 11:42:13 volumio volumio[921]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 11:42:14 volumio volumio[921]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} May 18 11:42:14 volumio ntpd[1914]: Soliciting pool server 89.46.74.148 May 18 11:42:14 volumio ntpd[1914]: Soliciting pool server 162.159.200.1 May 18 11:42:14 volumio ntpd[1914]: Soliciting pool server 95.110.254.234 May 18 11:42:14 volumio volumio[921]: info: Updating MyVolumio device info May 18 11:42:14 volumio volumio[921]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 11:42:14 volumio volumio[921]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 11:42:14 volumio volumio[921]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 11:42:15 volumio volumio[921]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} May 18 11:42:15 volumio volumio[921]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 18 11:42:15 volumio volumio[921]: info: Received Get System Info May 18 11:42:15 volumio volumio[921]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 18 11:42:15 volumio volumio[921]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 18 11:42:15 volumio volumio[921]: info: Discovery: Getting this device information May 18 11:42:15 volumio volumio[921]: info: CoreCommandRouter::volumioGetState May 18 11:42:15 volumio volumio[921]: info: CorePlayQueue::getTrack 0 May 18 11:42:15 volumio volumio[921]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 18 11:42:15 volumio ntpd[1914]: Soliciting pool server 93.44.243.48 May 18 11:42:15 volumio ntpd[1914]: Soliciting pool server 212.45.144.3 May 18 11:42:15 volumio ntpd[1914]: Soliciting pool server 195.32.70.195 May 18 11:42:16 volumio volumio[2474]: [Vollibrespot] : couldn't parse packet from 192.168.1.156:5353: type 47 is invalid May 18 11:42:18 volumio volumio[921]: info: MYVOLUMIO: Adding device May 18 11:42:18 volumio volumio[921]: info: MYVOLUMIO: Evaluating Server May 18 11:42:18 volumio volumio-time-update[665]: volumio-time-update-util: Fetching time from Volumio... May 18 11:42:18 volumio volumio-time-update[665]: volumio-time-update-util: Setting system time to: 2025-05-18 11:42:17 May 18 11:42:18 volumio sudo[2552]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-05-18 11:42:17 May 18 11:42:18 volumio sudo[2552]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 11:42:18 volumio dbus-daemon[659]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.16' (uid=0 pid=2554 comm="timedatectl set-time 2025-05-18 11:42:17 ") May 18 11:42:18 volumio systemd[1]: Starting Time & Date Service... May 18 11:42:18 volumio dbus-daemon[659]: [system] Successfully activated service 'org.freedesktop.timedate1' May 18 11:42:18 volumio systemd[1]: Started Time & Date Service. May 18 11:42:17 volumio systemd-timedated[2557]: Changed local time to Sun May 18 11:42:17 2025 May 18 11:42:17 volumio sudo[2552]: pam_unix(sudo:session): session closed for user root May 18 11:42:17 volumio volumio-time-update[665]: volumio-time-update-util: System time updated successfully. May 18 11:42:17 volumio systemd[1]: Started Volumio Time Update Utility. May 18 11:42:17 volumio systemd[1]: Reached target Multi-User System. May 18 11:42:17 volumio systemd[1]: Reached target Graphical Interface. May 18 11:42:17 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... May 18 11:42:17 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. May 18 11:42:17 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. May 18 11:42:17 volumio systemd[1]: Startup finished in 21.324s (kernel) + 4min 26.757s (userspace) = 4min 48.081s. May 18 11:42:17 volumio volumio[921]: error: Failed to ping endpoint eu8.myvolumio.org : unknown error May 18 11:42:17 volumio volumio[921]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 18 11:42:17 volumio volumio[921]: Error: Unable to resolve or reject the same promise twice May 18 11:42:17 volumio volumio[921]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) May 18 11:42:17 volumio volumio[921]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32367) May 18 11:42:17 volumio volumio[921]: at Socket.emit (events.js:400:28) May 18 11:42:17 volumio volumio[921]: at addChunk (internal/streams/readable.js:293:12) May 18 11:42:17 volumio volumio[921]: at readableAddChunk (internal/streams/readable.js:267:9) May 18 11:42:17 volumio volumio[921]: at Socket.Readable.push (internal/streams/readable.js:206:10) May 18 11:42:17 volumio volumio[921]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) May 18 11:42:17 volumio volumio[921]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 18 11:42:17 volumio sudo[2579]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-18 11:41 May 18 11:42:17 volumio sudo[2579]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="a01a833dd28483f88cb390596f5090b7187992af" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 27 Feb 2025 12:15:38 PM CET" VOLUMIO_VERSION="3.795" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="9e0e048b157c8fb0f19240808f3da58b"