-- Logs begin at Thu 2024-10-10 12:29:17 CEST, end at Thu 2024-10-10 12:31:25 CEST. -- Oct 10 12:30:00 volumio systemd[1]: Starting Network Time Service... Oct 10 12:30:00 volumio ntpd[1904]: ntpd 4.2.8p12@1.3728-o (1): Starting Oct 10 12:30:00 volumio ntpd[1904]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Oct 10 12:30:00 volumio systemd[1]: Started Network Time Service. Oct 10 12:30:00 volumio dhcpcd[1159]: eth0: deleted route to 169.254.0.0/16 Oct 10 12:30:00 volumio dhcpcd[1159]: eth0: adding route to 169.254.0.0/16 Oct 10 12:30:00 volumio dhcpcd[1159]: if_route (ADD): Invalid argument Oct 10 12:30:00 volumio dhcpcd[1159]: eth0: adding default route Oct 10 12:30:00 volumio dhcpcd[1159]: if_route (ADD): Invalid argument Oct 10 12:30:00 volumio avahi-daemon[789]: Withdrawing address record for 169.254.160.144 on eth0. Oct 10 12:30:00 volumio avahi-daemon[789]: Leaving mDNS multicast group on interface eth0.IPv4 with address 169.254.160.144. Oct 10 12:30:00 volumio avahi-daemon[789]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.31. Oct 10 12:30:00 volumio ntpd[1910]: proto: precision = 0.068 usec (-24) Oct 10 12:30:00 volumio ntpd[1910]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Oct 10 12:30:00 volumio dhcpcd[867]: eth0: deleting route to 169.254.0.0/16 Oct 10 12:30:00 volumio ntpd[1910]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Oct 10 12:30:00 volumio ntpd[1910]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 653 days ago Oct 10 12:30:00 volumio ntpd[1910]: Listen and drop on 0 v6wildcard [::]:123 Oct 10 12:30:00 volumio ntpd[1910]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Oct 10 12:30:00 volumio ntpd[1910]: Listen normally on 2 lo 127.0.0.1:123 Oct 10 12:30:00 volumio ntpd[1910]: Listen normally on 3 eth0 192.168.1.31:123 Oct 10 12:30:00 volumio ntpd[1910]: Listening on routing socket on fd #20 for interface updates Oct 10 12:30:00 volumio ntpd[1910]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 10 12:30:00 volumio ntpd[1910]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 10 12:30:00 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 10 12:30:00 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 10 12:30:00 volumio volumio[1234]: info: Discovery: Getting this device information Oct 10 12:30:00 volumio volumio[1234]: info: CoreCommandRouter::volumioGetState Oct 10 12:30:00 volumio volumio[1234]: info: CorePlayQueue::getTrack 0 Oct 10 12:30:00 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 10 12:30:00 volumio volumio[1234]: verbose: New Socket.io Connection to 192.168.1.31:3000 from 192.168.1.20 UA: Dart/3.4 (dart:io) Engine version: 3 Transport: websocket Total Clients: 11 Oct 10 12:30:00 volumio volumio[1234]: info: CoreCommandRouter::volumioGetState Oct 10 12:30:00 volumio volumio[1234]: info: CorePlayQueue::getTrack 0 Oct 10 12:30:00 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 10 12:30:00 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 10 12:30:01 volumio ntpd[1910]: Soliciting pool server 129.250.35.251 Oct 10 12:30:01 volumio volumio[1852]: ...............................................................................................................................................................................................................................++++ Oct 10 12:30:01 volumio volumio[1234]: info: CoreCommandRouter::volumioGetState Oct 10 12:30:01 volumio volumio[1234]: info: CorePlayQueue::getTrack 0 Oct 10 12:30:01 volumio volumio[1234]: info: Discovery: adding 15e0bb6c-285a-4ab3-bd57-14d039a0a718 Oct 10 12:30:01 volumio volumio[1234]: info: Discovery: Found device Volumio Oct 10 12:30:01 volumio volumio[1234]: info: CoreCommandRouter::volumioGetState Oct 10 12:30:01 volumio volumio[1234]: info: CorePlayQueue::getTrack 0 Oct 10 12:30:01 volumio dhcpcd[1159]: eth0: leased 192.168.1.31 for 86400 seconds Oct 10 12:30:01 volumio dhcpcd[1159]: eth0: adding route to 192.168.1.0/24 Oct 10 12:30:01 volumio dhcpcd[1159]: eth0: adding default route via 192.168.1.1 Oct 10 12:30:01 volumio dhcpcd[1159]: eth0: adding route to 169.254.0.0/16 Oct 10 12:30:01 volumio dhcpcd[1159]: if_route (ADD): Invalid argument Oct 10 12:30:01 volumio ntpd[1910]: ntpd exiting on signal 15 (Terminated) Oct 10 12:30:01 volumio ntpd[1910]: 129.250.35.251 local addr 192.168.1.31 -> Oct 10 12:30:01 volumio systemd[1]: Stopping Network Time Service... Oct 10 12:30:01 volumio systemd[1]: ntp.service: Succeeded. Oct 10 12:30:01 volumio systemd[1]: Stopped Network Time Service. Oct 10 12:30:01 volumio systemd[1]: Starting Network Time Service... Oct 10 12:30:01 volumio ntpd[1959]: ntpd 4.2.8p12@1.3728-o (1): Starting Oct 10 12:30:01 volumio ntpd[1959]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Oct 10 12:30:01 volumio systemd[1]: Started Network Time Service. Oct 10 12:30:01 volumio dhcpcd[1159]: wlan0: ipv4_deladdr: Bad file descriptor Oct 10 12:30:01 volumio ntpd[1965]: proto: precision = 0.066 usec (-24) Oct 10 12:30:01 volumio kernel: traps: dhcpcd[1159] general protection fault ip:55dca0678cc8 sp:7fffe33b5b30 error:0 in dhcpcd[55dca065a000+32000] Oct 10 12:30:01 volumio ntpd[1965]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Oct 10 12:30:01 volumio ntpd[1965]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Oct 10 12:30:01 volumio ntpd[1965]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 653 days ago Oct 10 12:30:01 volumio ntpd[1965]: Listen and drop on 0 v6wildcard [::]:123 Oct 10 12:30:01 volumio ntpd[1965]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Oct 10 12:30:01 volumio ntpd[1965]: Listen normally on 2 lo 127.0.0.1:123 Oct 10 12:30:01 volumio ntpd[1965]: Listen normally on 3 eth0 192.168.1.31:123 Oct 10 12:30:01 volumio ntpd[1965]: Listening on routing socket on fd #20 for interface updates Oct 10 12:30:01 volumio ntpd[1965]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 10 12:30:01 volumio ntpd[1965]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 10 12:30:02 volumio volumio[1234]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Oct 10 12:30:02 volumio volumio[1234]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Oct 10 12:30:02 volumio volumio[1234]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Oct 10 12:30:02 volumio volumio[1234]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Oct 10 12:30:02 volumio volumio[1234]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Oct 10 12:30:02 volumio volumio[1234]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Oct 10 12:30:02 volumio volumio[1234]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Oct 10 12:30:02 volumio volumio[1234]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Oct 10 12:30:02 volumio volumio[1234]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Oct 10 12:30:02 volumio volumio[1234]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Oct 10 12:30:02 volumio volumio[1234]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Oct 10 12:30:02 volumio volumio[1234]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Oct 10 12:30:02 volumio volumio[1234]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Oct 10 12:30:02 volumio volumio[1234]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Oct 10 12:30:02 volumio volumio[1234]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Oct 10 12:30:02 volumio volumio[1234]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Oct 10 12:30:02 volumio volumio[1234]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Oct 10 12:30:02 volumio volumio[1234]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Oct 10 12:30:02 volumio volumio[1234]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Oct 10 12:30:02 volumio volumio[1234]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Oct 10 12:30:02 volumio volumio[1234]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Oct 10 12:30:02 volumio volumio[1234]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Oct 10 12:30:02 volumio volumio[1234]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Oct 10 12:30:02 volumio volumio[1234]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Oct 10 12:30:02 volumio volumio[1234]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Oct 10 12:30:02 volumio volumio[1234]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Oct 10 12:30:02 volumio volumio[1234]: info: Adding plugin bluetooth to MyMusic Plugins Oct 10 12:30:02 volumio volumio[1234]: info: Adding plugin multiroom to MyMusic Plugins Oct 10 12:30:02 volumio volumio[1234]: info: Adding plugin metavolumio to MyMusic Plugins Oct 10 12:30:02 volumio volumio[1234]: info: Adding plugin cd_controller to MyMusic Plugins Oct 10 12:30:02 volumio volumio[1234]: info: Adding plugin smart_inputs to MyMusic Plugins Oct 10 12:30:02 volumio volumio[1234]: info: Adding plugin tidalconnect to MyMusic Plugins Oct 10 12:30:02 volumio volumio[1234]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Oct 10 12:30:02 volumio volumio[1852]: .............................................................................++++ Oct 10 12:30:02 volumio volumio[1852]: e is 65537 (0x010001) Oct 10 12:30:02 volumio volumio[1852]: writing RSA key Oct 10 12:30:02 volumio volumio[1234]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 10 12:30:02 volumio volumio[1234]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 10 12:30:02 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 12:30:02 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 12:30:02 volumio volumio[1234]: info: Starting MyVolumio Remote Streaming Endpoints Oct 10 12:30:02 volumio volumio[1234]: info: MyVolumio login type: Token Oct 10 12:30:02 volumio volumio[1234]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 10 12:30:02 volumio volumio[1234]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Oct 10 12:30:02 volumio ntpd[1965]: Soliciting pool server 82.64.247.11 Oct 10 12:30:02 volumio volumio[1234]: info: Starting Streaming Service Transparent Proxy Oct 10 12:30:02 volumio volumio[1234]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Oct 10 12:30:02 volumio volumio[1234]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Oct 10 12:30:02 volumio volumio[1234]: info: Streaming services startup Oct 10 12:30:02 volumio volumio[1234]: info: Starting Streaming Daemon Oct 10 12:30:03 volumio sudo[1978]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 10 12:30:03 volumio sudo[1978]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 12:30:03 volumio volumio[1234]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Oct 10 12:30:03 volumio sudo[1978]: pam_unix(sudo:session): session closed for user root Oct 10 12:30:03 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 10 12:30:03 volumio volumio[1234]: info: Received Get System Info Oct 10 12:30:03 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 10 12:30:03 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 10 12:30:03 volumio volumio[1234]: info: Discovery: Getting this device information Oct 10 12:30:03 volumio volumio[1234]: info: CoreCommandRouter::volumioGetState Oct 10 12:30:03 volumio volumio[1234]: info: CorePlayQueue::getTrack 0 Oct 10 12:30:03 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 10 12:30:03 volumio volumio[1234]: error: Cannot start Volumio Streaming Daemon Oct 10 12:30:03 volumio volumio[1234]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 10 12:30:03 volumio volumio[1234]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 10 12:30:03 volumio volumio[1234]: STREAMING PROXY: Starting server on port 3245 Oct 10 12:30:03 volumio volumio[1234]: Node JS runtime: 14 Oct 10 12:30:03 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Oct 10 12:30:03 volumio volumio[1234]: info: MyVolumio login type: Token Oct 10 12:30:03 volumio volumio[1234]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Oct 10 12:30:03 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 10 12:30:03 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 10 12:30:03 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 10 12:30:03 volumio volumio[1234]: info: Discovery: Getting this device information Oct 10 12:30:03 volumio volumio[1234]: info: CoreCommandRouter::volumioGetState Oct 10 12:30:03 volumio volumio[1234]: info: CorePlayQueue::getTrack 0 Oct 10 12:30:03 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 10 12:30:03 volumio ntpd[1965]: Soliciting pool server 212.85.158.10 Oct 10 12:30:03 volumio ntpd[1965]: Soliciting pool server 212.83.158.83 Oct 10 12:30:03 volumio volumio[1656]: [Vollibrespot] : Failed to resolve Access Point: HTTP error Oct 10 12:30:03 volumio volumio[1656]: [Vollibrespot] : Using fallback "ap.spotify.com:443" Oct 10 12:30:03 volumio volumio[1656]: [Vollibrespot] : Connecting to AP "ap.spotify.com:443" Oct 10 12:30:03 volumio volumio[1234]: info: MyVolumio token set successfully Oct 10 12:30:03 volumio volumio[1234]: info: MYVOLUMIO: Adding device Oct 10 12:30:03 volumio volumio[1234]: info: MYVOLUMIO: Evaluating Server Oct 10 12:30:04 volumio volumio[1656]: thread 'main' panicked at 'Authentication failed with reason: BadCredentials', /build/cache/git/checkouts/librespot-6f197fd632ef9380/08d8bcc/core/src/connection/mod.rs:93:21 Oct 10 12:30:04 volumio volumio[1656]: stack backtrace: Oct 10 12:30:04 volumio volumio[1656]: 0: 0x562128e5e0cd - std::backtrace_rs::backtrace::libunwind::trace::hee598835bc88d35b Oct 10 12:30:04 volumio volumio[1656]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/../../backtrace/src/backtrace/libunwind.rs:93:5 Oct 10 12:30:04 volumio volumio[1656]: 1: 0x562128e5e0cd - std::backtrace_rs::backtrace::trace_unsynchronized::h9cdc730ba5cf5d72 Oct 10 12:30:04 volumio volumio[1656]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 Oct 10 12:30:04 volumio volumio[1656]: 2: 0x562128e5e0cd - std::sys_common::backtrace::_print_fmt::h75aeaf7ed30e43fa Oct 10 12:30:04 volumio volumio[1656]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:66:5 Oct 10 12:30:04 volumio volumio[1656]: 3: 0x562128e5e0cd - ::fmt::h606862f787600875 Oct 10 12:30:04 volumio volumio[1656]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:45:22 Oct 10 12:30:04 volumio volumio[1656]: 4: 0x562128e83f5c - core::fmt::write::he803f0f418caf762 Oct 10 12:30:04 volumio volumio[1656]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/fmt/mod.rs:1190:17 Oct 10 12:30:04 volumio volumio[1656]: 5: 0x562128e56cb8 - std::io::Write::write_fmt::h70bc45872f37e7bb Oct 10 12:30:04 volumio volumio[1656]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/io/mod.rs:1657:15 Oct 10 12:30:04 volumio volumio[1656]: 6: 0x562128e605a7 - std::sys_common::backtrace::_print::h64d038cf8ac3e13e Oct 10 12:30:04 volumio volumio[1656]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:48:5 Oct 10 12:30:04 volumio volumio[1656]: 7: 0x562128e605a7 - std::sys_common::backtrace::print::h359300b4a7fccf65 Oct 10 12:30:04 volumio volumio[1656]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:35:9 Oct 10 12:30:04 volumio volumio[1656]: 8: 0x562128e605a7 - std::panicking::default_hook::{{closure}}::hf51be35e2f510149 Oct 10 12:30:04 volumio volumio[1656]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:295:22 Oct 10 12:30:04 volumio volumio[1656]: 9: 0x562128e60270 - std::panicking::default_hook::h03ca0f22e1d2d25e Oct 10 12:30:04 volumio volumio[1656]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:314:9 Oct 10 12:30:04 volumio volumio[1656]: 10: 0x562128e60cf9 - std::panicking::rust_panic_with_hook::h3b7380e99b825b63 Oct 10 12:30:04 volumio volumio[1656]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:698:17 Oct 10 12:30:04 volumio volumio[1656]: 11: 0x562128e609e7 - std::panicking::begin_panic_handler::{{closure}}::h8e849d0710154ce0 Oct 10 12:30:04 volumio volumio[1656]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:588:13 Oct 10 12:30:04 volumio volumio[1656]: 12: 0x562128e5e594 - std::sys_common::backtrace::__rust_end_short_backtrace::hedcdaddbd4c46cc5 Oct 10 12:30:04 volumio volumio[1656]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:138:18 Oct 10 12:30:04 volumio volumio[1656]: 13: 0x562128e606f9 - rust_begin_unwind Oct 10 12:30:04 volumio volumio[1656]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:584:5 Oct 10 12:30:04 volumio volumio[1656]: 14: 0x562128969f53 - core::panicking::panic_fmt::he1bbc7336d49a357 Oct 10 12:30:04 volumio volumio[1656]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/panicking.rs:143:14 Oct 10 12:30:04 volumio volumio[1656]: 15: 0x562128b39f56 - futures::future::chain::Chain::poll::hf0e729e3d4d8df0d Oct 10 12:30:04 volumio volumio[1656]: 16: 0x562128baca19 - as futures::future::Future>::poll::hb2bebc352335cc1a Oct 10 12:30:04 volumio volumio[1656]: 17: 0x562128b388e6 - futures::future::chain::Chain::poll::hda5263cfb178296b Oct 10 12:30:04 volumio volumio[1234]: info: MyVolumio Plan changed: premium Oct 10 12:30:04 volumio volumio[1234]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Oct 10 12:30:04 volumio volumio[1656]: 18: 0x562128badfe1 - as futures::future::Future>::poll::h7445370ea1c2ee99 Oct 10 12:30:04 volumio volumio[1234]: info: Removing browser output: myVolumio user plan is not superstar Oct 10 12:30:04 volumio volumio[1656]: 19: 0x562128997736 - ::poll::hdecdb1a04917de4b Oct 10 12:30:04 volumio volumio[1656]: 20: 0x5621289af149 - futures::task_impl::std::set::h6dd4b73f744a06d4 Oct 10 12:30:04 volumio volumio[1656]: 21: 0x5621289c53bd - std::thread::local::LocalKey::with::hc9bf2e930c1fdc36 Oct 10 12:30:04 volumio volumio[1234]: info: Removing audio output: Oct 10 12:30:04 volumio volumio[1234]: info: MYVOLUMIO: Adding device Oct 10 12:30:04 volumio volumio[1234]: info: MYVOLUMIO: Evaluating Server Oct 10 12:30:04 volumio volumio[1656]: 22: 0x5621289abc56 - tokio_current_thread::Entered

::block_on::h372d7b837fe25d26 Oct 10 12:30:04 volumio volumio[1656]: 23: 0x5621289c4b48 - std::thread::local::LocalKey::with::h247079172a6ad00b Oct 10 12:30:04 volumio volumio[1656]: 24: 0x5621289ae8c5 - tokio::runtime::current_thread::runtime::Runtime::block_on::h538e89536cb974b3 Oct 10 12:30:04 volumio volumio[1656]: 25: 0x5621289997e9 - vollibrespot::main::hf67292b633164777 Oct 10 12:30:04 volumio volumio[1656]: 26: 0x5621289b3c73 - std::sys_common::backtrace::__rust_begin_short_backtrace::h9396fe1ba8d4dd7f Oct 10 12:30:04 volumio volumio[1656]: 27: 0x5621289d6ce9 - std::rt::lang_start::{{closure}}::h0862538761d46dbc Oct 10 12:30:04 volumio volumio[1656]: 28: 0x562128e5d7b1 - core::ops::function::impls:: for &F>::call_once::hb7014f43484a8b4e Oct 10 12:30:04 volumio volumio[1656]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/ops/function.rs:259:13 Oct 10 12:30:04 volumio volumio[1656]: 29: 0x562128e5d7b1 - std::panicking::try::do_call::h7bc9dc436daeb8c7 Oct 10 12:30:04 volumio volumio[1656]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:492:40 Oct 10 12:30:04 volumio volumio[1656]: 30: 0x562128e5d7b1 - std::panicking::try::h653d68a27ff5f175 Oct 10 12:30:04 volumio volumio[1656]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:456:19 Oct 10 12:30:04 volumio volumio[1656]: 31: 0x562128e5d7b1 - std::panic::catch_unwind::h9d739f9f59895e68 Oct 10 12:30:04 volumio volumio[1656]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panic.rs:137:14 Oct 10 12:30:04 volumio volumio[1656]: 32: 0x562128e5d7b1 - std::rt::lang_start_internal::{{closure}}::hf006f2bc7ce22bbe Oct 10 12:30:04 volumio volumio[1656]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/rt.rs:128:48 Oct 10 12:30:04 volumio volumio[1656]: 33: 0x562128e5d7b1 - std::panicking::try::do_call::hfb39d6df61a2e69f Oct 10 12:30:04 volumio volumio[1656]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:492:40 Oct 10 12:30:04 volumio volumio[1656]: 34: 0x562128e5d7b1 - std::panicking::try::h13e2d225134958ac Oct 10 12:30:04 volumio volumio[1656]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:456:19 Oct 10 12:30:04 volumio volumio[1656]: 35: 0x562128e5d7b1 - std::panic::catch_unwind::h3bd49b5a5dfb1a50 Oct 10 12:30:04 volumio volumio[1656]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panic.rs:137:14 Oct 10 12:30:04 volumio volumio[1656]: 36: 0x562128e5d7b1 - std::rt::lang_start_internal::h2ba92edce36c035e Oct 10 12:30:04 volumio volumio[1656]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/rt.rs:128:20 Oct 10 12:30:04 volumio volumio[1656]: 37: 0x56212899c562 - main Oct 10 12:30:04 volumio volumio[1656]: 38: 0x7f7be9dd509b - __libc_start_main Oct 10 12:30:04 volumio volumio[1234]: info: Remote config written successfully Oct 10 12:30:04 volumio volumio[1656]: 39: 0x56212896a78a - _start Oct 10 12:30:04 volumio volumio[1656]: 40: 0x0 - Oct 10 12:30:04 volumio volumio[1234]: info: Starting Tunnel 1 Oct 10 12:30:04 volumio volumio[1234]: info: Starting Tunnel Connection Checker Oct 10 12:30:04 volumio volumio[1234]: info: Completed starting MyVolumio Plugin Oct 10 12:30:04 volumio systemd[1]: volspotconnect.service: Main process exited, code=exited, status=101/n/a Oct 10 12:30:04 volumio systemd[1]: volspotconnect.service: Failed with result 'exit-code'. Oct 10 12:30:04 volumio volumio[1234]: info: MYVolumio Device enabled Oct 10 12:30:04 volumio volumio[1234]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Oct 10 12:30:04 volumio volumio[1234]: info: MyVolumio status changed Oct 10 12:30:04 volumio volumio[1234]: info: Streaming services startup Oct 10 12:30:04 volumio volumio[1234]: info: Starting Streaming Daemon Oct 10 12:30:04 volumio sudo[2017]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 10 12:30:04 volumio sudo[2017]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 12:30:04 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Oct 10 12:30:04 volumio sudo[2017]: pam_unix(sudo:session): session closed for user root Oct 10 12:30:04 volumio volumio[1234]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Oct 10 12:30:04 volumio volumio[1234]: info: [1728556204646] Starting BluetoothController Oct 10 12:30:04 volumio volumio[1234]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Oct 10 12:30:04 volumio ntpd[1965]: Soliciting pool server 54.38.242.85 Oct 10 12:30:04 volumio ntpd[1965]: Soliciting pool server 82.65.248.56 Oct 10 12:30:04 volumio ntpd[1965]: Soliciting pool server 162.159.200.1 Oct 10 12:30:05 volumio volumio[1234]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Oct 10 12:30:05 volumio sudo[2022]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Oct 10 12:30:05 volumio sudo[2022]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 12:30:05 volumio sudo[2022]: pam_unix(sudo:session): session closed for user root Oct 10 12:30:05 volumio volumio[1234]: info: MRS: MultiRoom plugin initialized Oct 10 12:30:05 volumio volumio[1234]: info: MRS: STOPPING SNAPCLIENT Oct 10 12:30:05 volumio volumio[1234]: info: MRS: Snap server stop Oct 10 12:30:05 volumio sudo[2038]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Oct 10 12:30:05 volumio sudo[2038]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 12:30:05 volumio volumio[1234]: info: MRS: STOPPING volumioStreaming Oct 10 12:30:05 volumio sudo[2041]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Oct 10 12:30:05 volumio sudo[2038]: pam_unix(sudo:session): session closed for user root Oct 10 12:30:05 volumio sudo[2041]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 12:30:05 volumio volumio[1234]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Oct 10 12:30:05 volumio sudo[2044]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Oct 10 12:30:05 volumio sudo[2044]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 12:30:05 volumio sudo[2041]: pam_unix(sudo:session): session closed for user root Oct 10 12:30:05 volumio sudo[2046]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Oct 10 12:30:05 volumio sudo[2046]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 12:30:05 volumio sudo[2046]: pam_unix(sudo:session): session closed for user root Oct 10 12:30:05 volumio volumio[1234]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Oct 10 12:30:05 volumio sudo[2044]: pam_unix(sudo:session): session closed for user root Oct 10 12:30:05 volumio volumio[1234]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Oct 10 12:30:05 volumio volumio[1234]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Oct 10 12:30:05 volumio volumio[1234]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Oct 10 12:30:05 volumio volumio[1234]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Oct 10 12:30:05 volumio volumio[1234]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Oct 10 12:30:05 volumio volumio[1234]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Oct 10 12:30:05 volumio volumio[1234]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Oct 10 12:30:05 volumio volumio[1234]: info: Preparing to generate the ALSA configuration file Oct 10 12:30:05 volumio volumio[1234]: info: Setting Geolocation for MyVolumio to eu4 Oct 10 12:30:05 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 12:30:05 volumio volumio[1234]: info: Setting Geolocation for MyVolumio to eu6 Oct 10 12:30:05 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 12:30:05 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 12:30:05 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 12:30:05 volumio volumio[1234]: info: MRS: Removed streaming files Oct 10 12:30:05 volumio volumio[1234]: info: MRS: volumioStreaming STOPPED Oct 10 12:30:05 volumio volumio[1234]: info: MRS: SNAPSERVER STOPPED Oct 10 12:30:05 volumio volumio[1234]: info: MRS: SNAPCLIENT STOPPED Oct 10 12:30:05 volumio volumio[1234]: error: Cannot start Volumio Streaming Daemon Oct 10 12:30:05 volumio volumio[1234]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 10 12:30:05 volumio volumio[1234]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 10 12:30:05 volumio volumio[1234]: info: Asound.conf file unchanged, so no further update is needed Oct 10 12:30:05 volumio volumio[1234]: info: Output device has changed, restarting MPD Oct 10 12:30:05 volumio volumio[1234]: info: Output device has changed, restarting Shairport Sync Oct 10 12:30:05 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 12:30:05 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 12:30:05 volumio sudo[2050]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 10 12:30:05 volumio sudo[2050]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 12:30:05 volumio sudo[2050]: pam_unix(sudo:session): session closed for user root Oct 10 12:30:05 volumio sudo[2052]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 10 12:30:05 volumio sudo[2052]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 12:30:05 volumio systemd[1]: Stopping Music Player Daemon... Oct 10 12:30:05 volumio volumio[1234]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 10 12:30:05 volumio volumio[1234]: [SpotifyConnect] Creating VLS config file Oct 10 12:30:05 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 12:30:05 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 12:30:05 volumio volumio[1234]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 10 12:30:05 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 12:30:05 volumio volumio[1234]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Oct 10 12:30:05 volumio volumio[1234]: info: [MyVolumio PluginManager] Plugin bluetooth disabled by user. Not starting Oct 10 12:30:05 volumio volumio[1234]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Oct 10 12:30:05 volumio volumio[1234]: info: [MyVolumio PluginManager] Plugin multiroom disabled by user. Not starting Oct 10 12:30:05 volumio volumio[1234]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Oct 10 12:30:05 volumio volumio[1234]: info: [MyVolumio PluginManager] Plugin metavolumio disabled by user. Not starting Oct 10 12:30:05 volumio volumio[1234]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Oct 10 12:30:05 volumio volumio[1234]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Oct 10 12:30:05 volumio volumio[1234]: info: [MyVolumio PluginManager] Plugin cd_controller disabled by user. Not starting Oct 10 12:30:05 volumio volumio[1234]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Oct 10 12:30:05 volumio volumio[1234]: info: [MyVolumio PluginManager] Plugin smart_inputs disabled by user. Not starting Oct 10 12:30:05 volumio volumio[1234]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Oct 10 12:30:05 volumio systemd[1]: mpd.service: Succeeded. Oct 10 12:30:05 volumio systemd[1]: Stopped Music Player Daemon. Oct 10 12:30:05 volumio volumio[1234]: error: Hi Res Audio Failed Login: Missing Login Data Oct 10 12:30:05 volumio volumio[1234]: info: Adding HIGHRESAUDIO REST API Endpoints Oct 10 12:30:05 volumio volumio[1234]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Oct 10 12:30:05 volumio volumio[1234]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Oct 10 12:30:05 volumio volumio[1234]: info: Refreshing TIDAL token Oct 10 12:30:05 volumio volumio[1234]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Oct 10 12:30:05 volumio volumio[1234]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Oct 10 12:30:05 volumio volumio[1234]: info: [MyVolumio PluginManager] Plugin tidalconnect disabled by user. Not starting Oct 10 12:30:05 volumio volumio[1234]: info: Stopping AccessToken refresher cron for QOBUZ Oct 10 12:30:05 volumio systemd[1]: Starting Music Player Daemon... Oct 10 12:30:05 volumio volumio[1234]: info: AccessToken refresher cron started for QOBUZ Oct 10 12:30:05 volumio volumio[1234]: info: Adding QOBUZ REST API Endpoints Oct 10 12:30:05 volumio volumio[1234]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Oct 10 12:30:05 volumio volumio[1234]: [SpotifyConnect] Restarting Vollibrespot Daemon Oct 10 12:30:05 volumio volumio[1234]: info: MPD Permissions set Oct 10 12:30:05 volumio sudo[2062]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service Oct 10 12:30:05 volumio sudo[2062]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 12:30:05 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 12:30:05 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 12:30:05 volumio sudo[2060]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 10 12:30:05 volumio sudo[2060]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 12:30:05 volumio systemd[1]: Stopped Volspotconnect2 Daemon. Oct 10 12:30:05 volumio sudo[2060]: pam_unix(sudo:session): session closed for user root Oct 10 12:30:05 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 12:30:05 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 10 12:30:05 volumio systemd[1]: Started Volspotconnect2 Daemon. Oct 10 12:30:05 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 12:30:05 volumio sudo[2062]: pam_unix(sudo:session): session closed for user root Oct 10 12:30:05 volumio volumio[2065]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 Oct 10 12:30:05 volumio volumio[2065]: Reading Config from "/tmp/volspotify.toml" Oct 10 12:30:05 volumio volumio[2065]: [Vollibrespot] : Using Alsa backend with device: volumio Oct 10 12:30:05 volumio volumio[1234]: info: Starting Shairport Sync Oct 10 12:30:05 volumio volumio[1234]: [SpotifyConnect] Vollibrespot Daemon service restarted! Oct 10 12:30:05 volumio sudo[2079]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 10 12:30:05 volumio sudo[2079]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 12:30:05 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 10 12:30:05 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 10 12:30:05 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 10 12:30:05 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 10 12:30:05 volumio sudo[2079]: pam_unix(sudo:session): session closed for user root Oct 10 12:30:05 volumio volumio[1234]: info: Shairport-Sync Started Oct 10 12:30:05 volumio volumio[2065]: [Vollibrespot] : Connecting to AP "ap-gew1.spotify.com:443" Oct 10 12:30:05 volumio ntpd[1965]: Soliciting pool server 5.196.160.139 Oct 10 12:30:05 volumio ntpd[1965]: Soliciting pool server 37.59.63.125 Oct 10 12:30:05 volumio ntpd[1965]: Soliciting pool server 51.210.104.72 Oct 10 12:30:06 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 10 12:30:06 volumio volumio[1234]: info: Received Get System Info Oct 10 12:30:06 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 10 12:30:06 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 10 12:30:06 volumio volumio[1234]: info: Discovery: Getting this device information Oct 10 12:30:06 volumio volumio[1234]: info: CoreCommandRouter::volumioGetState Oct 10 12:30:06 volumio volumio[1234]: info: CorePlayQueue::getTrack 0 Oct 10 12:30:06 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 10 12:30:06 volumio volumio[2065]: thread 'main' panicked at 'Authentication failed with reason: BadCredentials', /build/cache/git/checkouts/librespot-6f197fd632ef9380/08d8bcc/core/src/connection/mod.rs:93:21 Oct 10 12:30:06 volumio volumio[2065]: stack backtrace: Oct 10 12:30:06 volumio volumio[2065]: 0: 0x55f97c5fb0cd - std::backtrace_rs::backtrace::libunwind::trace::hee598835bc88d35b Oct 10 12:30:06 volumio volumio[2065]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/../../backtrace/src/backtrace/libunwind.rs:93:5 Oct 10 12:30:06 volumio volumio[2065]: 1: 0x55f97c5fb0cd - std::backtrace_rs::backtrace::trace_unsynchronized::h9cdc730ba5cf5d72 Oct 10 12:30:06 volumio volumio[2065]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 Oct 10 12:30:06 volumio volumio[2065]: 2: 0x55f97c5fb0cd - std::sys_common::backtrace::_print_fmt::h75aeaf7ed30e43fa Oct 10 12:30:06 volumio volumio[2065]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:66:5 Oct 10 12:30:06 volumio volumio[2065]: 3: 0x55f97c5fb0cd - ::fmt::h606862f787600875 Oct 10 12:30:06 volumio volumio[2065]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:45:22 Oct 10 12:30:06 volumio volumio[2065]: 4: 0x55f97c620f5c - core::fmt::write::he803f0f418caf762 Oct 10 12:30:06 volumio volumio[2065]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/fmt/mod.rs:1190:17 Oct 10 12:30:06 volumio volumio[2065]: 5: 0x55f97c5f3cb8 - std::io::Write::write_fmt::h70bc45872f37e7bb Oct 10 12:30:06 volumio volumio[2065]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/io/mod.rs:1657:15 Oct 10 12:30:06 volumio volumio[2065]: 6: 0x55f97c5fd5a7 - std::sys_common::backtrace::_print::h64d038cf8ac3e13e Oct 10 12:30:06 volumio volumio[2065]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:48:5 Oct 10 12:30:06 volumio volumio[2065]: 7: 0x55f97c5fd5a7 - std::sys_common::backtrace::print::h359300b4a7fccf65 Oct 10 12:30:06 volumio volumio[2065]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:35:9 Oct 10 12:30:06 volumio volumio[2065]: 8: 0x55f97c5fd5a7 - std::panicking::default_hook::{{closure}}::hf51be35e2f510149 Oct 10 12:30:06 volumio volumio[2065]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:295:22 Oct 10 12:30:06 volumio volumio[2065]: 9: 0x55f97c5fd270 - std::panicking::default_hook::h03ca0f22e1d2d25e Oct 10 12:30:06 volumio volumio[2065]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:314:9 Oct 10 12:30:06 volumio volumio[2065]: 10: 0x55f97c5fdcf9 - std::panicking::rust_panic_with_hook::h3b7380e99b825b63 Oct 10 12:30:06 volumio volumio[2065]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:698:17 Oct 10 12:30:06 volumio volumio[2065]: 11: 0x55f97c5fd9e7 - std::panicking::begin_panic_handler::{{closure}}::h8e849d0710154ce0 Oct 10 12:30:06 volumio volumio[2065]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:588:13 Oct 10 12:30:06 volumio volumio[2065]: 12: 0x55f97c5fb594 - std::sys_common::backtrace::__rust_end_short_backtrace::hedcdaddbd4c46cc5 Oct 10 12:30:06 volumio volumio[2065]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:138:18 Oct 10 12:30:06 volumio volumio[2065]: 13: 0x55f97c5fd6f9 - rust_begin_unwind Oct 10 12:30:06 volumio volumio[2065]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:584:5 Oct 10 12:30:06 volumio volumio[2065]: 14: 0x55f97c106f53 - core::panicking::panic_fmt::he1bbc7336d49a357 Oct 10 12:30:06 volumio volumio[2065]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/panicking.rs:143:14 Oct 10 12:30:06 volumio volumio[2065]: 15: 0x55f97c2d6f56 - futures::future::chain::Chain::poll::hf0e729e3d4d8df0d Oct 10 12:30:06 volumio volumio[2065]: 16: 0x55f97c349a19 - as futures::future::Future>::poll::hb2bebc352335cc1a Oct 10 12:30:06 volumio volumio[2065]: 17: 0x55f97c2d58e6 - futures::future::chain::Chain::poll::hda5263cfb178296b Oct 10 12:30:06 volumio volumio[2065]: 18: 0x55f97c34afe1 - as futures::future::Future>::poll::h7445370ea1c2ee99 Oct 10 12:30:06 volumio volumio[2065]: 19: 0x55f97c134736 - ::poll::hdecdb1a04917de4b Oct 10 12:30:06 volumio volumio[2065]: 20: 0x55f97c14c149 - futures::task_impl::std::set::h6dd4b73f744a06d4 Oct 10 12:30:06 volumio volumio[2065]: 21: 0x55f97c1623bd - std::thread::local::LocalKey::with::hc9bf2e930c1fdc36 Oct 10 12:30:06 volumio volumio[2065]: 22: 0x55f97c148c56 - tokio_current_thread::Entered

::block_on::h372d7b837fe25d26 Oct 10 12:30:06 volumio volumio[2065]: 23: 0x55f97c161b48 - std::thread::local::LocalKey::with::h247079172a6ad00b Oct 10 12:30:06 volumio volumio[2065]: 24: 0x55f97c14b8c5 - tokio::runtime::current_thread::runtime::Runtime::block_on::h538e89536cb974b3 Oct 10 12:30:06 volumio volumio[2065]: 25: 0x55f97c1367e9 - vollibrespot::main::hf67292b633164777 Oct 10 12:30:06 volumio volumio[2065]: 26: 0x55f97c150c73 - std::sys_common::backtrace::__rust_begin_short_backtrace::h9396fe1ba8d4dd7f Oct 10 12:30:06 volumio volumio[2065]: 27: 0x55f97c173ce9 - std::rt::lang_start::{{closure}}::h0862538761d46dbc Oct 10 12:30:06 volumio volumio[2065]: 28: 0x55f97c5fa7b1 - core::ops::function::impls:: for &F>::call_once::hb7014f43484a8b4e Oct 10 12:30:06 volumio volumio[2065]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/ops/function.rs:259:13 Oct 10 12:30:06 volumio volumio[2065]: 29: 0x55f97c5fa7b1 - std::panicking::try::do_call::h7bc9dc436daeb8c7 Oct 10 12:30:06 volumio volumio[2065]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:492:40 Oct 10 12:30:06 volumio volumio[2065]: 30: 0x55f97c5fa7b1 - std::panicking::try::h653d68a27ff5f175 Oct 10 12:30:06 volumio volumio[2065]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:456:19 Oct 10 12:30:06 volumio volumio[2065]: 31: 0x55f97c5fa7b1 - std::panic::catch_unwind::h9d739f9f59895e68 Oct 10 12:30:06 volumio volumio[2065]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panic.rs:137:14 Oct 10 12:30:06 volumio volumio[2065]: 32: 0x55f97c5fa7b1 - std::rt::lang_start_internal::{{closure}}::hf006f2bc7ce22bbe Oct 10 12:30:06 volumio volumio[2065]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/rt.rs:128:48 Oct 10 12:30:06 volumio volumio[2065]: 33: 0x55f97c5fa7b1 - std::panicking::try::do_call::hfb39d6df61a2e69f Oct 10 12:30:06 volumio volumio[2065]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:492:40 Oct 10 12:30:06 volumio volumio[2065]: 34: 0x55f97c5fa7b1 - std::panicking::try::h13e2d225134958ac Oct 10 12:30:06 volumio volumio[2065]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:456:19 Oct 10 12:30:06 volumio volumio[2065]: 35: 0x55f97c5fa7b1 - std::panic::catch_unwind::h3bd49b5a5dfb1a50 Oct 10 12:30:06 volumio volumio[2065]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panic.rs:137:14 Oct 10 12:30:06 volumio volumio[2065]: 36: 0x55f97c5fa7b1 - std::rt::lang_start_internal::h2ba92edce36c035e Oct 10 12:30:06 volumio volumio[2065]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/rt.rs:128:20 Oct 10 12:30:06 volumio volumio[2065]: 37: 0x55f97c139562 - main Oct 10 12:30:06 volumio volumio[2065]: 38: 0x7fa68530109b - __libc_start_main Oct 10 12:30:06 volumio volumio[2065]: 39: 0x55f97c10778a - _start Oct 10 12:30:06 volumio volumio[2065]: 40: 0x0 - Oct 10 12:30:06 volumio systemd[1]: volspotconnect.service: Main process exited, code=exited, status=101/n/a Oct 10 12:30:06 volumio systemd[1]: volspotconnect.service: Failed with result 'exit-code'. Oct 10 12:30:06 volumio mpd[2071]: Oct 10 12:30 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 10 12:30:06 volumio systemd[1]: Started Music Player Daemon. Oct 10 12:30:06 volumio sudo[2052]: pam_unix(sudo:session): session closed for user root Oct 10 12:30:06 volumio volumio[1234]: error: updateQueue error: null Oct 10 12:30:06 volumio volumio[1234]: info: Successfully Added MyVolumio device Oct 10 12:30:06 volumio volumio[1234]: verbose: New Socket.io Connection to 192.168.1.31 from 192.168.1.64 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/122.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Oct 10 12:30:06 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 10 12:30:06 volumio volumio[1234]: info: CoreCommandRouter::volumioGetVisibleSources Oct 10 12:30:06 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 10 12:30:06 volumio volumio[1234]: info: CoreCommandRouter::volumioGetState Oct 10 12:30:06 volumio volumio[1234]: info: CorePlayQueue::getTrack 0 Oct 10 12:30:06 volumio volumio[1234]: info: CoreCommandRouter::volumioGetState Oct 10 12:30:06 volumio volumio[1234]: info: CorePlayQueue::getTrack 0 Oct 10 12:30:06 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 10 12:30:06 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 10 12:30:06 volumio volumio[1234]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 10 12:30:06 volumio volumio[1234]: info: Received Get System Info Oct 10 12:30:06 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 10 12:30:06 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 10 12:30:06 volumio volumio[1234]: info: Discovery: Getting this device information Oct 10 12:30:06 volumio volumio[1234]: info: CoreCommandRouter::volumioGetState Oct 10 12:30:06 volumio volumio[1234]: info: CorePlayQueue::getTrack 0 Oct 10 12:30:06 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 10 12:30:06 volumio volumio[1234]: info: CoreCommandRouter::volumioGetState Oct 10 12:30:06 volumio volumio[1234]: info: CorePlayQueue::getTrack 0 Oct 10 12:30:06 volumio volumio[1234]: info: Listing playlists Oct 10 12:30:06 volumio volumio[1234]: info: Access Token successfully retrieved Oct 10 12:30:06 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 10 12:30:06 volumio volumio[1234]: info: Updating MyVolumio device info Oct 10 12:30:06 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 12:30:06 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 12:30:06 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 12:30:06 volumio volumio[1234]: info: Updating MyVolumio device info Oct 10 12:30:06 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 12:30:06 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 12:30:06 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 12:30:06 volumio volumio[1234]: info: Successfully Added MyVolumio device Oct 10 12:30:06 volumio volumio[1234]: info: Successfully retrieved User Session From TIDAL Oct 10 12:30:06 volumio volumio[1234]: info: Successfully retrieved User Subscription From TIDAL Oct 10 12:30:06 volumio volumio[1234]: info: Adding TIDAL to Browse Sources Oct 10 12:30:06 volumio volumio[1234]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 10 12:30:06 volumio volumio[1234]: info: [1728556206717] CoreMusicLibrary::Adding element TIDAL Oct 10 12:30:06 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 10 12:30:06 volumio volumio[1234]: Cannot find translation for source TIDAL Oct 10 12:30:06 volumio volumio[1234]: info: Stopping AccessToken refresher cron Oct 10 12:30:06 volumio volumio[1234]: info: AccessToken refresher cron started Oct 10 12:30:06 volumio volumio[1234]: info: Adding TIDAL REST API Endpoints Oct 10 12:30:06 volumio volumio[1234]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Oct 10 12:30:06 volumio volumio[1234]: info: BOOT COMPLETED Oct 10 12:30:06 volumio volumio[1234]: [Metrics] CommandRouter: 19s 413.79ms Oct 10 12:30:06 volumio volumio[1234]: info: CoreCommandRouter::volumiosetStartupVolume Oct 10 12:30:06 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 12:30:06 volumio volumio[1234]: info: VolumeController:: Setting startup Volume 75 Oct 10 12:30:06 volumio volumio[1234]: info: VolumeController::SetAlsaVolume75 Oct 10 12:30:06 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 12:30:06 volumio volumio[1234]: info: CoreCommandRouter::Close All Modals sent Oct 10 12:30:06 volumio volumio[1234]: info: CoreCommandRouter::Close All Modals sent Oct 10 12:30:06 volumio volumio[1234]: info: CoreStateMachine::pushState Oct 10 12:30:06 volumio volumio[1234]: info: CorePlayQueue::getTrack 0 Oct 10 12:30:06 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 10 12:30:06 volumio volumio[1234]: info: CoreCommandRouter::volumioPushState Oct 10 12:30:06 volumio volumio[1234]: info: Successfully Updated MyVolumio device Oct 10 12:30:06 volumio ntpd[1965]: Soliciting pool server 54.36.61.42 Oct 10 12:30:06 volumio ntpd[1965]: Soliciting pool server 51.195.104.188 Oct 10 12:30:06 volumio ntpd[1965]: Soliciting pool server 82.127.104.72 Oct 10 12:30:07 volumio sudo[2090]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 10 12:30:07 volumio sudo[2090]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 12:30:07 volumio sudo[2090]: pam_unix(sudo:session): session closed for user root Oct 10 12:30:07 volumio sudo[2093]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 10 12:30:07 volumio sudo[2093]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 12:30:07 volumio sudo[2093]: pam_unix(sudo:session): session closed for user root Oct 10 12:30:07 volumio volumio[1234]: info: Successfully Updated MyVolumio device Oct 10 12:30:07 volumio volumio[1234]: verbose: New Socket.io Connection to 192.168.1.31 from 192.168.1.20 UA: Mozilla/5.0 (Linux; Android 10; CLT-L29 Build/HUAWEICLT-L29; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/129.0.6668.81 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 Oct 10 12:30:07 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 10 12:30:07 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 10 12:30:07 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 10 12:30:07 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 10 12:30:07 volumio ntpd[1965]: Soliciting pool server 37.187.118.149 Oct 10 12:30:07 volumio ntpd[1965]: Soliciting pool server 178.32.23.17 Oct 10 12:30:07 volumio volumio[1234]: info: CoreCommandRouter::volumioGetVisibleSources Oct 10 12:30:07 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 10 12:30:07 volumio volumio[1234]: info: CoreCommandRouter::volumioGetState Oct 10 12:30:07 volumio volumio[1234]: info: CorePlayQueue::getTrack 0 Oct 10 12:30:07 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 10 12:30:07 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 10 12:30:07 volumio volumio[1234]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 10 12:30:07 volumio volumio[1234]: info: Received Get System Info Oct 10 12:30:07 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 10 12:30:07 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 10 12:30:07 volumio volumio[1234]: info: Discovery: Getting this device information Oct 10 12:30:07 volumio volumio[1234]: info: CoreCommandRouter::volumioGetState Oct 10 12:30:07 volumio volumio[1234]: info: CorePlayQueue::getTrack 0 Oct 10 12:30:07 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 10 12:30:07 volumio volumio[1234]: info: CoreCommandRouter::volumioGetState Oct 10 12:30:07 volumio volumio[1234]: info: CorePlayQueue::getTrack 0 Oct 10 12:30:07 volumio volumio[1234]: info: Listing playlists Oct 10 12:30:07 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 10 12:30:07 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 10 12:30:08 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 10 12:30:08 volumio sudo[2098]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 10 12:30:08 volumio sudo[2098]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 12:30:08 volumio sudo[2098]: pam_unix(sudo:session): session closed for user root Oct 10 12:30:08 volumio sudo[2101]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 10 12:30:08 volumio sudo[2101]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 12:30:08 volumio systemd[1]: volspotconnect.service: Service RestartSec=2s expired, scheduling restart. Oct 10 12:30:08 volumio systemd[1]: volspotconnect.service: Scheduled restart job, restart counter is at 2. Oct 10 12:30:08 volumio systemd[1]: Stopped Volspotconnect2 Daemon. Oct 10 12:30:08 volumio sudo[2101]: pam_unix(sudo:session): session closed for user root Oct 10 12:30:08 volumio systemd[1]: Started Volspotconnect2 Daemon. Oct 10 12:30:08 volumio volumio[2103]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 Oct 10 12:30:08 volumio volumio[2103]: Reading Config from "/tmp/volspotify.toml" Oct 10 12:30:08 volumio volumio[2103]: [Vollibrespot] : Using Alsa backend with device: volumio Oct 10 12:30:08 volumio volumio[2103]: [Vollibrespot] : Connecting to AP "ap-gew1.spotify.com:443" Oct 10 12:30:08 volumio volumio[1234]: verbose: New Socket.io Connection to 192.168.1.31 from 192.168.1.20 UA: Mozilla/5.0 (Linux; Android 10; CLT-L29 Build/HUAWEICLT-L29; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/129.0.6668.81 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 14 Oct 10 12:30:08 volumio volumio[2103]: thread 'main' panicked at 'Authentication failed with reason: BadCredentials', /build/cache/git/checkouts/librespot-6f197fd632ef9380/08d8bcc/core/src/connection/mod.rs:93:21 Oct 10 12:30:08 volumio volumio[2103]: stack backtrace: Oct 10 12:30:08 volumio volumio[2103]: 0: 0x55f2c2db40cd - std::backtrace_rs::backtrace::libunwind::trace::hee598835bc88d35b Oct 10 12:30:08 volumio volumio[2103]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/../../backtrace/src/backtrace/libunwind.rs:93:5 Oct 10 12:30:08 volumio volumio[2103]: 1: 0x55f2c2db40cd - std::backtrace_rs::backtrace::trace_unsynchronized::h9cdc730ba5cf5d72 Oct 10 12:30:08 volumio volumio[2103]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 Oct 10 12:30:08 volumio volumio[2103]: 2: 0x55f2c2db40cd - std::sys_common::backtrace::_print_fmt::h75aeaf7ed30e43fa Oct 10 12:30:08 volumio volumio[2103]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:66:5 Oct 10 12:30:08 volumio volumio[2103]: 3: 0x55f2c2db40cd - ::fmt::h606862f787600875 Oct 10 12:30:08 volumio volumio[2103]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:45:22 Oct 10 12:30:08 volumio volumio[2103]: 4: 0x55f2c2dd9f5c - core::fmt::write::he803f0f418caf762 Oct 10 12:30:08 volumio volumio[2103]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/fmt/mod.rs:1190:17 Oct 10 12:30:08 volumio volumio[2103]: 5: 0x55f2c2daccb8 - std::io::Write::write_fmt::h70bc45872f37e7bb Oct 10 12:30:08 volumio volumio[2103]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/io/mod.rs:1657:15 Oct 10 12:30:08 volumio volumio[2103]: 6: 0x55f2c2db65a7 - std::sys_common::backtrace::_print::h64d038cf8ac3e13e Oct 10 12:30:08 volumio volumio[2103]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:48:5 Oct 10 12:30:08 volumio volumio[2103]: 7: 0x55f2c2db65a7 - std::sys_common::backtrace::print::h359300b4a7fccf65 Oct 10 12:30:08 volumio volumio[2103]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:35:9 Oct 10 12:30:08 volumio volumio[2103]: 8: 0x55f2c2db65a7 - std::panicking::default_hook::{{closure}}::hf51be35e2f510149 Oct 10 12:30:08 volumio volumio[2103]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:295:22 Oct 10 12:30:08 volumio volumio[2103]: 9: 0x55f2c2db6270 - std::panicking::default_hook::h03ca0f22e1d2d25e Oct 10 12:30:08 volumio volumio[2103]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:314:9 Oct 10 12:30:08 volumio volumio[2103]: 10: 0x55f2c2db6cf9 - std::panicking::rust_panic_with_hook::h3b7380e99b825b63 Oct 10 12:30:08 volumio volumio[2103]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:698:17 Oct 10 12:30:08 volumio volumio[2103]: 11: 0x55f2c2db69e7 - std::panicking::begin_panic_handler::{{closure}}::h8e849d0710154ce0 Oct 10 12:30:08 volumio volumio[2103]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:588:13 Oct 10 12:30:08 volumio volumio[2103]: 12: 0x55f2c2db4594 - std::sys_common::backtrace::__rust_end_short_backtrace::hedcdaddbd4c46cc5 Oct 10 12:30:08 volumio volumio[2103]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:138:18 Oct 10 12:30:08 volumio volumio[2103]: 13: 0x55f2c2db66f9 - rust_begin_unwind Oct 10 12:30:08 volumio volumio[2103]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:584:5 Oct 10 12:30:08 volumio volumio[2103]: 14: 0x55f2c28bff53 - core::panicking::panic_fmt::he1bbc7336d49a357 Oct 10 12:30:08 volumio volumio[2103]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/panicking.rs:143:14 Oct 10 12:30:08 volumio volumio[2103]: 15: 0x55f2c2a8ff56 - futures::future::chain::Chain::poll::hf0e729e3d4d8df0d Oct 10 12:30:08 volumio volumio[2103]: 16: 0x55f2c2b02a19 - as futures::future::Future>::poll::hb2bebc352335cc1a Oct 10 12:30:08 volumio volumio[2103]: 17: 0x55f2c2a8e8e6 - futures::future::chain::Chain::poll::hda5263cfb178296b Oct 10 12:30:08 volumio volumio[2103]: 18: 0x55f2c2b03fe1 - as futures::future::Future>::poll::h7445370ea1c2ee99 Oct 10 12:30:08 volumio volumio[2103]: 19: 0x55f2c28ed736 - ::poll::hdecdb1a04917de4b Oct 10 12:30:08 volumio volumio[2103]: 20: 0x55f2c2905149 - futures::task_impl::std::set::h6dd4b73f744a06d4 Oct 10 12:30:08 volumio volumio[2103]: 21: 0x55f2c291b3bd - std::thread::local::LocalKey::with::hc9bf2e930c1fdc36 Oct 10 12:30:08 volumio volumio[2103]: 22: 0x55f2c2901c56 - tokio_current_thread::Entered

::block_on::h372d7b837fe25d26 Oct 10 12:30:08 volumio volumio[2103]: 23: 0x55f2c291ab48 - std::thread::local::LocalKey::with::h247079172a6ad00b Oct 10 12:30:08 volumio volumio[2103]: 24: 0x55f2c29048c5 - tokio::runtime::current_thread::runtime::Runtime::block_on::h538e89536cb974b3 Oct 10 12:30:08 volumio volumio[2103]: 25: 0x55f2c28ef7e9 - vollibrespot::main::hf67292b633164777 Oct 10 12:30:08 volumio volumio[2103]: 26: 0x55f2c2909c73 - std::sys_common::backtrace::__rust_begin_short_backtrace::h9396fe1ba8d4dd7f Oct 10 12:30:08 volumio volumio[2103]: 27: 0x55f2c292cce9 - std::rt::lang_start::{{closure}}::h0862538761d46dbc Oct 10 12:30:08 volumio volumio[2103]: 28: 0x55f2c2db37b1 - core::ops::function::impls:: for &F>::call_once::hb7014f43484a8b4e Oct 10 12:30:08 volumio volumio[2103]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/ops/function.rs:259:13 Oct 10 12:30:08 volumio volumio[2103]: 29: 0x55f2c2db37b1 - std::panicking::try::do_call::h7bc9dc436daeb8c7 Oct 10 12:30:08 volumio volumio[2103]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:492:40 Oct 10 12:30:08 volumio volumio[2103]: 30: 0x55f2c2db37b1 - std::panicking::try::h653d68a27ff5f175 Oct 10 12:30:08 volumio volumio[2103]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:456:19 Oct 10 12:30:08 volumio volumio[2103]: 31: 0x55f2c2db37b1 - std::panic::catch_unwind::h9d739f9f59895e68 Oct 10 12:30:08 volumio volumio[2103]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panic.rs:137:14 Oct 10 12:30:08 volumio volumio[2103]: 32: 0x55f2c2db37b1 - std::rt::lang_start_internal::{{closure}}::hf006f2bc7ce22bbe Oct 10 12:30:08 volumio volumio[2103]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/rt.rs:128:48 Oct 10 12:30:08 volumio volumio[2103]: 33: 0x55f2c2db37b1 - std::panicking::try::do_call::hfb39d6df61a2e69f Oct 10 12:30:08 volumio volumio[2103]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:492:40 Oct 10 12:30:08 volumio volumio[2103]: 34: 0x55f2c2db37b1 - std::panicking::try::h13e2d225134958ac Oct 10 12:30:08 volumio volumio[2103]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:456:19 Oct 10 12:30:08 volumio volumio[2103]: 35: 0x55f2c2db37b1 - std::panic::catch_unwind::h3bd49b5a5dfb1a50 Oct 10 12:30:08 volumio volumio[2103]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panic.rs:137:14 Oct 10 12:30:08 volumio volumio[2103]: 36: 0x55f2c2db37b1 - std::rt::lang_start_internal::h2ba92edce36c035e Oct 10 12:30:08 volumio volumio[2103]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/rt.rs:128:20 Oct 10 12:30:08 volumio volumio[2103]: 37: 0x55f2c28f2562 - main Oct 10 12:30:08 volumio volumio[2103]: 38: 0x7f494eaa109b - __libc_start_main Oct 10 12:30:08 volumio volumio[2103]: 39: 0x55f2c28c078a - _start Oct 10 12:30:08 volumio volumio[2103]: 40: 0x0 - Oct 10 12:30:08 volumio systemd[1]: volspotconnect.service: Main process exited, code=exited, status=101/n/a Oct 10 12:30:08 volumio systemd[1]: volspotconnect.service: Failed with result 'exit-code'. Oct 10 12:30:08 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 10 12:30:08 volumio volumio[1234]: info: CoreCommandRouter::volumioGetVisibleSources Oct 10 12:30:08 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 10 12:30:08 volumio volumio[1234]: info: CoreCommandRouter::volumioGetState Oct 10 12:30:08 volumio volumio[1234]: info: CorePlayQueue::getTrack 0 Oct 10 12:30:08 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 10 12:30:08 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 10 12:30:08 volumio volumio[1234]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 10 12:30:08 volumio volumio[1234]: info: Received Get System Info Oct 10 12:30:08 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 10 12:30:08 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 10 12:30:08 volumio volumio[1234]: info: Discovery: Getting this device information Oct 10 12:30:08 volumio volumio[1234]: info: CoreCommandRouter::volumioGetState Oct 10 12:30:08 volumio volumio[1234]: info: CorePlayQueue::getTrack 0 Oct 10 12:30:08 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 10 12:30:08 volumio volumio[1234]: info: CoreCommandRouter::volumioGetState Oct 10 12:30:08 volumio volumio[1234]: info: CorePlayQueue::getTrack 0 Oct 10 12:30:08 volumio volumio[1234]: info: Listing playlists Oct 10 12:30:08 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 10 12:30:08 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 10 12:30:08 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 10 12:30:08 volumio ntpd[1965]: Soliciting pool server 5.39.80.51 Oct 10 12:30:08 volumio ntpd[1965]: Soliciting pool server 2001:41d0:a:56ae::123 Oct 10 12:30:09 volumio sudo[2114]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Oct 10 12:30:09 volumio sudo[2114]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 12:30:09 volumio systemd[1]: Started MyVolumio SSH Tunnel. Oct 10 12:30:09 volumio sudo[2114]: pam_unix(sudo:session): session closed for user root Oct 10 12:30:09 volumio volumio[1234]: info: Remote SSH Started Oct 10 12:30:09 volumio autossh[2117]: port set to 0, monitoring disabled Oct 10 12:30:09 volumio autossh[2117]: starting ssh (count 1) Oct 10 12:30:09 volumio autossh[2117]: ssh child pid is 2120 Oct 10 12:30:09 volumio volumio[1234]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 15 Oct 10 12:30:09 volumio volumio[1234]: info: CoreCommandRouter::volumioGetState Oct 10 12:30:09 volumio volumio[1234]: info: CorePlayQueue::getTrack 0 Oct 10 12:30:09 volumio volumiossh-tunnel[2116]: Warning: Permanently added '[eu6.myvolumio.org]:2222,[207.154.233.230]:2222' (RSA) to the list of known hosts. Oct 10 12:30:10 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 10 12:30:10 volumio volumio[1234]: info: Received Get System Info Oct 10 12:30:10 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 10 12:30:10 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 10 12:30:10 volumio volumio[1234]: info: Discovery: Getting this device information Oct 10 12:30:10 volumio volumio[1234]: info: CoreCommandRouter::volumioGetState Oct 10 12:30:10 volumio volumio[1234]: info: CorePlayQueue::getTrack 0 Oct 10 12:30:10 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 10 12:30:10 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 10 12:30:10 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 10 12:30:10 volumio systemd[1]: volspotconnect.service: Service RestartSec=2s expired, scheduling restart. Oct 10 12:30:10 volumio systemd[1]: volspotconnect.service: Scheduled restart job, restart counter is at 3. Oct 10 12:30:10 volumio systemd[1]: Stopped Volspotconnect2 Daemon. Oct 10 12:30:10 volumio systemd[1]: Started Volspotconnect2 Daemon. Oct 10 12:30:10 volumio volumio[2121]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 Oct 10 12:30:10 volumio volumio[2121]: Reading Config from "/tmp/volspotify.toml" Oct 10 12:30:10 volumio volumio[2121]: [Vollibrespot] : Using Alsa backend with device: volumio Oct 10 12:30:10 volumio volumio[2121]: [Vollibrespot] : Connecting to AP "ap-gew1.spotify.com:443" Oct 10 12:30:10 volumio volumio[2121]: [Vollibrespot] : Could not connect to server: Connection refused (os error 111) Oct 10 12:30:11 volumio volumio[1234]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=x86_amd64&variante=volumio&version=3.757&uuid=5d6077a52bb32789dc60c70d7a71e7ce" http://updates.volumio.org/downloader-v1/track-device Oct 10 12:30:11 volumio volumio[1234]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 10 12:30:11 volumio volumio[1234]: Dload Upload Total Spent Left Speed Oct 10 12:30:11 volumio volumio[1234]: [922B blob data] Oct 10 12:30:11 volumio volumio[1234]: retrying in 5 seconds, trial 0 Oct 10 12:30:11 volumio volumio[1234]: info: Volumio Calling Home Oct 10 12:30:11 volumio volumio[1234]: info: Checking Spotify Web API Oct 10 12:30:11 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 10 12:30:11 volumio volumio[1234]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 10 12:30:11 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 10 12:30:11 volumio volumio[1234]: info: Received Get System Version Oct 10 12:30:11 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 10 12:30:11 volumio volumio[1234]: info: Received Get System Info Oct 10 12:30:11 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 10 12:30:11 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 10 12:30:11 volumio volumio[1234]: info: Discovery: Getting this device information Oct 10 12:30:11 volumio volumio[1234]: info: CoreCommandRouter::volumioGetState Oct 10 12:30:11 volumio volumio[1234]: info: CorePlayQueue::getTrack 0 Oct 10 12:30:11 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 10 12:30:11 volumio volumio[1234]: info: Volumio called home Oct 10 12:30:13 volumio ntpd[1965]: receive: Unexpected origin timestamp 0xeab22b34.ed3680dd does not match aorg 0000000000.00000000 from server@5.196.160.139 xmt 0xeab22b35.135f8b9f Oct 10 12:30:13 volumio ntpd[1965]: receive: Unexpected origin timestamp 0xeab22b34.ed2f762f does not match aorg 0000000000.00000000 from server@51.210.104.72 xmt 0xeab22b35.13650fc3 Oct 10 12:30:17 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 10 12:30:17 volumio volumio[1234]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Oct 10 12:30:17 volumio volumio[1234]: info: CoreCommandRouter::volumioGetState Oct 10 12:30:17 volumio volumio[1234]: info: CorePlayQueue::getTrack 0 Oct 10 12:30:19 volumio volumio[1234]: verbose: New Socket.io Connection to 192.168.1.31 from 192.168.1.64 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/122.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 15 Oct 10 12:30:19 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 10 12:30:19 volumio volumio[1234]: info: CoreCommandRouter::volumioGetVisibleSources Oct 10 12:30:19 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 10 12:30:19 volumio volumio[1234]: info: CoreCommandRouter::volumioGetState Oct 10 12:30:19 volumio volumio[1234]: info: CorePlayQueue::getTrack 0 Oct 10 12:30:19 volumio volumio[1234]: info: CoreCommandRouter::volumioGetState Oct 10 12:30:19 volumio volumio[1234]: info: CorePlayQueue::getTrack 0 Oct 10 12:30:19 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 10 12:30:19 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 10 12:30:19 volumio volumio[1234]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 10 12:30:19 volumio volumio[1234]: info: Received Get System Info Oct 10 12:30:19 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 10 12:30:19 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 10 12:30:19 volumio volumio[1234]: info: Discovery: Getting this device information Oct 10 12:30:19 volumio volumio[1234]: info: CoreCommandRouter::volumioGetState Oct 10 12:30:19 volumio volumio[1234]: info: CorePlayQueue::getTrack 0 Oct 10 12:30:19 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 10 12:30:19 volumio volumio[1234]: info: CoreCommandRouter::volumioGetState Oct 10 12:30:19 volumio volumio[1234]: info: CorePlayQueue::getTrack 0 Oct 10 12:30:19 volumio volumio[1234]: info: Listing playlists Oct 10 12:30:19 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 10 12:30:19 volumio volumio[1234]: verbose: New Socket.io Connection to 192.168.1.31 from 192.168.1.64 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/122.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 16 Oct 10 12:30:21 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 10 12:30:21 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 12:30:21 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 10 12:30:21 volumio volumio-remote-updater[788]: No test mode Oct 10 12:30:21 volumio volumio-remote-updater[788]: No alpha test mode Oct 10 12:30:21 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Oct 10 12:30:21 volumio volumio[1234]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 10 12:30:21 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 10 12:30:22 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 12:30:22 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 12:30:22 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 10 12:30:22 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 10 12:30:22 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 10 12:30:22 volumio volumio[1234]: info: CoreCommandRouter::volumioGetBrowseSources Oct 10 12:30:22 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 10 12:30:33 volumio volumio[1234]: verbose: New Socket.io Connection to 192.168.1.31 from 192.168.1.64 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/122.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Oct 10 12:30:33 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 10 12:30:33 volumio volumio[1234]: info: CoreCommandRouter::volumioGetVisibleSources Oct 10 12:30:33 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 10 12:30:33 volumio volumio[1234]: info: CoreCommandRouter::volumioGetState Oct 10 12:30:33 volumio volumio[1234]: info: CorePlayQueue::getTrack 0 Oct 10 12:30:33 volumio volumio[1234]: info: CoreCommandRouter::volumioGetState Oct 10 12:30:33 volumio volumio[1234]: info: CorePlayQueue::getTrack 0 Oct 10 12:30:33 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 10 12:30:33 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 10 12:30:33 volumio volumio[1234]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 10 12:30:33 volumio volumio[1234]: info: Received Get System Info Oct 10 12:30:33 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 10 12:30:33 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 10 12:30:33 volumio volumio[1234]: info: Discovery: Getting this device information Oct 10 12:30:33 volumio volumio[1234]: info: CoreCommandRouter::volumioGetState Oct 10 12:30:33 volumio volumio[1234]: info: CorePlayQueue::getTrack 0 Oct 10 12:30:33 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 10 12:30:33 volumio volumio[1234]: info: CoreCommandRouter::volumioGetState Oct 10 12:30:33 volumio volumio[1234]: info: CorePlayQueue::getTrack 0 Oct 10 12:30:33 volumio volumio[1234]: info: Listing playlists Oct 10 12:30:33 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 10 12:30:52 volumio nmbd[1001]: [2024/10/10 12:30:52.248712, 0] ../source3/libsmb/nmblib.c:917(send_udp) Oct 10 12:30:52 volumio nmbd[1001]: Packet send failed to 169.254.255.255(138) ERRNO=Network is unreachable Oct 10 12:31:12 volumio ntpd[1965]: Soliciting pool server 129.250.35.251 Oct 10 12:31:13 volumio ntpd[1965]: Soliciting pool server 45.13.105.44 Oct 10 12:31:14 volumio ntpd[1965]: Soliciting pool server 129.151.225.244 Oct 10 12:31:19 volumio volumio[1234]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 10 12:31:19 volumio volumio[1234]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 10 12:31:19 volumio volumio[1234]: info: Retrieving Cloud Streaming UI Oct 10 12:31:19 volumio volumio[1234]: info: Getting Tidal Cloud Configuration Oct 10 12:31:19 volumio volumio[1234]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 10 12:31:19 volumio volumio[1234]: info: Getting Qobuz Cloud Configuration Oct 10 12:31:19 volumio volumio[1234]: info: Asking plugin for UI Config Oct 10 12:31:19 volumio volumio[1234]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 10 12:31:19 volumio volumio[1234]: info: Getting Spotify Cloud Configuration Oct 10 12:31:19 volumio volumio[1234]: info: Asking plugin for UI Config Oct 10 12:31:19 volumio volumio[1234]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 10 12:31:19 volumio volumio[1234]: info: Saving Spotify Acccount Oct 10 12:31:19 volumio volumio[1234]: info: Got it Oct 10 12:31:19 volumio volumio[1234]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 10 12:31:19 volumio volumio[1234]: info: Got Tidal Cloud Configuration Oct 10 12:31:19 volumio volumio[1234]: info: Got it Oct 10 12:31:19 volumio volumio[1234]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 10 12:31:19 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 10 12:31:19 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 10 12:31:19 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 10 12:31:19 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 10 12:31:19 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 10 12:31:19 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 10 12:31:19 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 10 12:31:19 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 10 12:31:19 volumio volumio[1234]: info: CoreCommandRouter::volumioGetBrowseSources Oct 10 12:31:19 volumio volumio[1234]: info: CoreCommandRouter::volumioGetBrowseSources Oct 10 12:31:19 volumio volumio[1234]: info: CoreCommandRouter::volumioGetBrowseSources Oct 10 12:31:20 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 10 12:31:20 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 10 12:31:22 volumio volumio[1234]: info: Disabling MyMusic plugin upnp Oct 10 12:31:22 volumio sudo[2233]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Oct 10 12:31:22 volumio sudo[2233]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 12:31:22 volumio systemd[1]: Stopping UPnP Renderer front-end to MPD... Oct 10 12:31:22 volumio volumio[1234]: error: Upnp client error: Error: This socket has been ended by the other party Oct 10 12:31:24 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 10 12:31:24 volumio volumio[1234]: info: Enabling MyMusic plugin upnp Oct 10 12:31:24 volumio volumio[1234]: info: Enabling plugin upnp Oct 10 12:31:24 volumio volumio[1234]: info: Loading plugin "upnp"... Oct 10 12:31:24 volumio volumio[1234]: info: [1728556284938] Starting Upmpd Daemon Oct 10 12:31:24 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 10 12:31:24 volumio volumio[1234]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 10 12:31:24 volumio volumio[1234]: Error: listen EADDRINUSE: address already in use :::6599 Oct 10 12:31:24 volumio volumio[1234]: at Server.setupListenHandle [as _listen2] (net.js:1331:16) Oct 10 12:31:24 volumio volumio[1234]: at listenInCluster (net.js:1379:12) Oct 10 12:31:24 volumio volumio[1234]: at Server.listen (net.js:1465:7) Oct 10 12:31:24 volumio volumio[1234]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Oct 10 12:31:24 volumio volumio[1234]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Oct 10 12:31:24 volumio volumio[1234]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Oct 10 12:31:24 volumio volumio[1234]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Oct 10 12:31:24 volumio volumio[1234]: at processTicksAndRejections (internal/process/task_queues.js:77:11) { Oct 10 12:31:24 volumio volumio[1234]: code: 'EADDRINUSE', Oct 10 12:31:24 volumio volumio[1234]: errno: -98, Oct 10 12:31:24 volumio volumio[1234]: syscall: 'listen', Oct 10 12:31:24 volumio volumio[1234]: address: '::', Oct 10 12:31:24 volumio volumio[1234]: port: 6599 Oct 10 12:31:24 volumio volumio[1234]: } Oct 10 12:31:24 volumio volumio[1234]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 10 12:31:25 volumio sudo[2244]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-10 12:30 Oct 10 12:31:25 volumio sudo[2244]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:33:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="ee834e1c2a28de3c5d8c48611ecf1167"