-- Logs begin at Mon 2023-07-10 05:16:59 PDT, end at Sat 2025-06-07 21:29:46 PDT. -- Jun 07 21:29:38 volumio kernel: usb 1-1.2: USB disconnect, device number 5 Jun 07 21:29:38 volumio volumio[779]: info: Jun 07 21:29:38 volumio volumio[779]: ---------------------------- USB Audio Device Detached Jun 07 21:29:38 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Jun 07 21:29:38 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 07 21:29:38 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Jun 07 21:29:38 volumio kernel: usb 1-1.2: new high-speed USB device number 6 using dwc_otg Jun 07 21:29:39 volumio kernel: usb 1-1.2: New USB device found, idVendor=152a, idProduct=8750, bcdDevice= 1.39 Jun 07 21:29:39 volumio kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Jun 07 21:29:39 volumio kernel: usb 1-1.2: Product: D50 III Jun 07 21:29:39 volumio kernel: usb 1-1.2: Manufacturer: Topping Jun 07 21:29:39 volumio kernel: usb 1-1.2: 1:3 : unsupported format bits 0x100000000 Jun 07 21:29:39 volumio kernel: usb 1-1.2: Warning! Unlikely big volume range (=2032), cval->res is probably wrong. Jun 07 21:29:39 volumio kernel: usb 1-1.2: [10] FU [D50 III Playback Volume] ch = 1, val = -32512/0/16 Jun 07 21:29:39 volumio kernel: input: Topping D50 III as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2/1-1.2:1.3/0003:152A:8750.0002/input/input1 Jun 07 21:29:39 volumio kernel: hid-generic 0003:152A:8750.0002: input,hidraw0: USB HID v1.10 Device [Topping D50 III] on usb-3f980000.usb-1.2/input3 Jun 07 21:29:39 volumio systemd-udevd[1091]: Process '/usr/sbin/alsactl -E HOME=/run/alsa restore 5' failed with exit code 99. Jun 07 21:29:39 volumio volumio[779]: info: Jun 07 21:29:39 volumio volumio[779]: ---------------------------- USB Audio Device Attached Jun 07 21:29:39 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Jun 07 21:29:39 volumio volumio[779]: info: CoreCommandRouter::Close All Modals sent Jun 07 21:29:39 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 07 21:29:39 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 07 21:29:39 volumio volumio[779]: info: Setting default mixerSoftMaster for Softvolume device Jun 07 21:29:39 volumio volumio[779]: [SpotifyConnect] Creating VLS config file Jun 07 21:29:39 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 21:29:39 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 21:29:39 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 21:29:39 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 21:29:39 volumio volumio[779]: [SpotifyConnect] Creating VLS config file Jun 07 21:29:39 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 21:29:39 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 21:29:39 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 21:29:39 volumio volumio[779]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 07 21:29:39 volumio volumio[779]: info: Updating Volume Controller Parameters: Device: 5 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jun 07 21:29:39 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jun 07 21:29:39 volumio volumio[779]: info: Disabling external Volume Control Jun 07 21:29:39 volumio systemd-udevd[1088]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 07 21:29:39 volumio volumio[779]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 07 21:29:39 volumio volumio[779]: info: Enable softmixer device for audio device undefined Jun 07 21:29:39 volumio volumio[779]: [SpotifyConnect] Restarting Vollibrespot Daemon Jun 07 21:29:39 volumio volumio[779]: [SpotifyConnect] Restarting Vollibrespot Daemon Jun 07 21:29:39 volumio volumio[779]: info: CoreStateMachine::pushState Jun 07 21:29:39 volumio volumio[779]: info: CorePlayQueue::getTrack 16 Jun 07 21:29:39 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 07 21:29:39 volumio volumio[779]: info: CoreCommandRouter::volumioPushState Jun 07 21:29:39 volumio volumio[779]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'SoftMaster',0 Jun 07 21:29:39 volumio volumio[779]: info: Software Volume ALSA configuration written Jun 07 21:29:39 volumio volumio[779]: info: Preparing to generate the ALSA configuration file Jun 07 21:29:39 volumio sudo[1130]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service Jun 07 21:29:39 volumio sudo[1128]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service Jun 07 21:29:39 volumio sudo[1130]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 21:29:39 volumio sudo[1128]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 21:29:39 volumio systemd[1]: Stopping Volspotconnect2 Daemon... Jun 07 21:29:39 volumio systemd[1]: volspotconnect.service: Main process exited, code=killed, status=15/TERM Jun 07 21:29:39 volumio systemd[1]: volspotconnect.service: Succeeded. Jun 07 21:29:39 volumio systemd[1]: Stopped Volspotconnect2 Daemon. Jun 07 21:29:39 volumio systemd[1]: Started Volspotconnect2 Daemon. Jun 07 21:29:39 volumio sudo[1130]: pam_unix(sudo:session): session closed for user root Jun 07 21:29:39 volumio sudo[1128]: pam_unix(sudo:session): session closed for user root Jun 07 21:29:39 volumio volumio[1134]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 Jun 07 21:29:39 volumio volumio[1134]: Reading Config from "/tmp/volspotify.toml" Jun 07 21:29:39 volumio volumio[1134]: [Vollibrespot] : Using Alsa backend with device: volumio Jun 07 21:29:39 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 07 21:29:39 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 21:29:39 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 07 21:29:39 volumio volumio[1134]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" } Jun 07 21:29:39 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 07 21:29:39 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 07 21:29:39 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 07 21:29:39 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 07 21:29:39 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 07 21:29:39 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 07 21:29:39 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 07 21:29:39 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 07 21:29:39 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 07 21:29:39 volumio volumio[779]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jun 07 21:29:39 volumio volumio[779]: info: Reading ALSA contributions from plugins. Jun 07 21:29:39 volumio volumio[779]: [SpotifyConnect] Vollibrespot Daemon service restarted! Jun 07 21:29:39 volumio volumio[779]: [SpotifyConnect] Vollibrespot Daemon service restarted! Jun 07 21:29:39 volumio volumio[779]: info: Asound.conf file unchanged, so no further update is needed Jun 07 21:29:39 volumio volumio[779]: info: Output device has changed, restarting MPD Jun 07 21:29:39 volumio volumio[1134]: [Vollibrespot] : Connecting to AP "ap-guc3.spotify.com:443" Jun 07 21:29:39 volumio sudo[1146]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 07 21:29:39 volumio sudo[1146]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 21:29:39 volumio volumio[779]: info: Output device has changed, restarting Shairport Sync Jun 07 21:29:39 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 21:29:39 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 21:29:39 volumio sudo[1146]: pam_unix(sudo:session): session closed for user root Jun 07 21:29:39 volumio sudo[1149]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 07 21:29:39 volumio sudo[1149]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 21:29:39 volumio systemd[1]: Stopping Music Player Daemon... Jun 07 21:29:39 volumio volumio[779]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 07 21:29:40 volumio volumio[779]: [SpotifyConnect] Creating VLS config file Jun 07 21:29:40 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 21:29:40 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 21:29:40 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 21:29:40 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 21:29:40 volumio volumio[779]: [SpotifyConnect] Creating VLS config file Jun 07 21:29:40 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 21:29:40 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 21:29:40 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 21:29:40 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 21:29:40 volumio volumio[779]: [SpotifyConnect] Restarting Vollibrespot Daemon Jun 07 21:29:40 volumio systemd[1]: mpd.service: Succeeded. Jun 07 21:29:40 volumio systemd[1]: Stopped Music Player Daemon. Jun 07 21:29:40 volumio systemd[1]: Starting Music Player Daemon... Jun 07 21:29:40 volumio volumio[779]: [SpotifyConnect] Restarting Vollibrespot Daemon Jun 07 21:29:40 volumio sudo[1156]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service Jun 07 21:29:40 volumio sudo[1156]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 21:29:40 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Jun 07 21:29:40 volumio volumio[779]: info: MPD Permissions set Jun 07 21:29:40 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 21:29:40 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 21:29:40 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 21:29:40 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 21:29:40 volumio systemd[1]: Stopping Volspotconnect2 Daemon... Jun 07 21:29:40 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 21:29:40 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 21:29:40 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 21:29:40 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 21:29:40 volumio systemd[1]: volspotconnect.service: Main process exited, code=killed, status=15/TERM Jun 07 21:29:40 volumio systemd[1]: volspotconnect.service: Succeeded. Jun 07 21:29:40 volumio systemd[1]: Stopped Volspotconnect2 Daemon. Jun 07 21:29:40 volumio systemd[1]: Started Volspotconnect2 Daemon. Jun 07 21:29:40 volumio sudo[1156]: pam_unix(sudo:session): session closed for user root Jun 07 21:29:40 volumio sudo[1159]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service Jun 07 21:29:40 volumio sudo[1159]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 21:29:40 volumio volumio[1160]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 Jun 07 21:29:40 volumio volumio[1160]: Reading Config from "/tmp/volspotify.toml" Jun 07 21:29:40 volumio volumio[1160]: [Vollibrespot] : Using Alsa backend with device: volumio Jun 07 21:29:40 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 21:29:40 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 21:29:40 volumio volumio[779]: [SpotifyConnect] Vollibrespot Daemon service restarted! Jun 07 21:29:40 volumio volumio[779]: info: Starting Shairport Sync Jun 07 21:29:40 volumio systemd[1]: Stopping Volspotconnect2 Daemon... Jun 07 21:29:40 volumio systemd[1]: volspotconnect.service: Main process exited, code=killed, status=15/TERM Jun 07 21:29:40 volumio systemd[1]: volspotconnect.service: Succeeded. Jun 07 21:29:40 volumio systemd[1]: Stopped Volspotconnect2 Daemon. Jun 07 21:29:40 volumio systemd[1]: Started Volspotconnect2 Daemon. Jun 07 21:29:40 volumio sudo[1159]: pam_unix(sudo:session): session closed for user root Jun 07 21:29:40 volumio sudo[1171]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 07 21:29:40 volumio volumio[779]: [SpotifyConnect] Vollibrespot Daemon service restarted! Jun 07 21:29:40 volumio volumio[1170]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 Jun 07 21:29:40 volumio volumio[1170]: Reading Config from "/tmp/volspotify.toml" Jun 07 21:29:40 volumio volumio[1170]: [Vollibrespot] : Using Alsa backend with device: volumio Jun 07 21:29:40 volumio sudo[1171]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 07 21:29:40 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 07 21:29:40 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 07 21:29:40 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 07 21:29:40 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 07 21:29:40 volumio sudo[1171]: pam_unix(sudo:session): session closed for user root Jun 07 21:29:40 volumio volumio[779]: info: Shairport-Sync Started Jun 07 21:29:40 volumio volumio[1170]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" } Jun 07 21:29:40 volumio volumio[1170]: [Vollibrespot] : Connecting to AP "ap-guc3.spotify.com:443" Jun 07 21:29:40 volumio volumio[1170]: thread 'main' panicked at 'Authentication failed with reason: BadCredentials', /build/cache/git/checkouts/librespot-6f197fd632ef9380/08d8bcc/core/src/connection/mod.rs:93:21 Jun 07 21:29:40 volumio volumio[1170]: stack backtrace: Jun 07 21:29:40 volumio volumio[1170]: 0: 0x9f8444 - std::backtrace_rs::backtrace::libunwind::trace::h40a4eff66330a36d Jun 07 21:29:40 volumio volumio[1170]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/../../backtrace/src/backtrace/libunwind.rs:93:5 Jun 07 21:29:40 volumio volumio[1170]: 1: 0x9f8444 - std::backtrace_rs::backtrace::trace_unsynchronized::hbce01963451b0cd5 Jun 07 21:29:40 volumio volumio[1170]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 Jun 07 21:29:40 volumio volumio[1170]: 2: 0x9f8444 - std::sys_common::backtrace::_print_fmt::h95da650d09c7e00b Jun 07 21:29:40 volumio volumio[1170]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:66:5 Jun 07 21:29:40 volumio volumio[1170]: 3: 0x9f8444 - ::fmt::h33b23b1d14f668ff Jun 07 21:29:40 volumio volumio[1170]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:45:22 Jun 07 21:29:40 volumio volumio[1170]: 4: 0xa210dc - core::fmt::write::h6320fb0e6ac90e58 Jun 07 21:29:40 volumio volumio[1170]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/fmt/mod.rs:1190:17 Jun 07 21:29:40 volumio volumio[1170]: 5: 0x9f04c4 - std::io::Write::write_fmt::h33b25d4810ca481f Jun 07 21:29:40 volumio volumio[1170]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/io/mod.rs:1657:15 Jun 07 21:29:40 volumio volumio[1170]: 6: 0x9faef4 - std::sys_common::backtrace::_print::h7e61c5be69c715ef Jun 07 21:29:40 volumio volumio[1170]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:48:5 Jun 07 21:29:40 volumio volumio[1170]: 7: 0x9faef4 - std::sys_common::backtrace::print::h3cee176d7c8dab8b Jun 07 21:29:40 volumio volumio[1170]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:35:9 Jun 07 21:29:40 volumio volumio[1170]: 8: 0x9faef4 - std::panicking::default_hook::{{closure}}::h2d0211dd2e97dc43 Jun 07 21:29:40 volumio volumio[1170]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:295:22 Jun 07 21:29:40 volumio volumio[1170]: 9: 0x9fab14 - std::panicking::default_hook::hcbdb3b7c40dca30c Jun 07 21:29:40 volumio volumio[1170]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:314:9 Jun 07 21:29:40 volumio volumio[1170]: 10: 0x9fb694 - std::panicking::rust_panic_with_hook::h4ce49e7a343dc12b Jun 07 21:29:40 volumio volumio[1170]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:698:17 Jun 07 21:29:40 volumio volumio[1170]: 11: 0x9fb304 - std::panicking::begin_panic_handler::{{closure}}::h4961100fae529fe9 Jun 07 21:29:40 volumio volumio[1170]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:588:13 Jun 07 21:29:40 volumio volumio[1170]: 12: 0x9f89c4 - std::sys_common::backtrace::__rust_end_short_backtrace::hf2e68e62c7a80f1f Jun 07 21:29:40 volumio volumio[1170]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:138:18 Jun 07 21:29:40 volumio volumio[1170]: 13: 0x9fb098 - rust_begin_unwind Jun 07 21:29:40 volumio volumio[1170]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:584:5 Jun 07 21:29:40 volumio volumio[1170]: 14: 0x4de6f4 - core::panicking::panic_fmt::h84c42730e4a50809 Jun 07 21:29:40 volumio volumio[1170]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/panicking.rs:143:14 Jun 07 21:29:40 volumio volumio[1170]: 15: 0x6ca930 - futures::future::chain::Chain::poll::hb08394d341f2da5e Jun 07 21:29:40 volumio volumio[1170]: 16: 0x748d4c - as futures::future::Future>::poll::h0222f8b810b74ccb Jun 07 21:29:40 volumio volumio[1170]: 17: 0x6cb53c - futures::future::chain::Chain::poll::hd3215a5f3268adbc Jun 07 21:29:40 volumio volumio[1170]: 18: 0x7415f0 - as futures::future::Future>::poll::hc28eaa01d8d9029a Jun 07 21:29:40 volumio volumio[1170]: 19: 0x505b34 - ::poll::h5fd9e3bcdeb25ad9 Jun 07 21:29:40 volumio volumio[1170]: 20: 0x5233dc - futures::task_impl::std::set::h8f754e80bd562d0a Jun 07 21:29:40 volumio volumio[1170]: 21: 0x53a42c - std::thread::local::LocalKey::with::h71cfa1e374fcc0a0 Jun 07 21:29:40 volumio volumio[1170]: 22: 0x51f99c - tokio_current_thread::Entered

::block_on::hde55cdf28eabe6d5 Jun 07 21:29:40 volumio volumio[1170]: 23: 0x53a6f0 - std::thread::local::LocalKey::with::hedba3cfe41326627 Jun 07 21:29:40 volumio volumio[1170]: 24: 0x522880 - tokio::runtime::current_thread::runtime::Runtime::block_on::h60d5b89560100ed8 Jun 07 21:29:40 volumio volumio[1170]: 25: 0x508eb0 - vollibrespot::main::h35555355ed947dd1 Jun 07 21:29:40 volumio volumio[1170]: 26: 0x528c50 - std::sys_common::backtrace::__rust_begin_short_backtrace::hef626a10d324c12a Jun 07 21:29:40 volumio volumio[1170]: 27: 0x54e52c - std::rt::lang_start::{{closure}}::hd80188e4bcac5072 Jun 07 21:29:40 volumio volumio[1170]: 28: 0x9f7b28 - core::ops::function::impls:: for &F>::call_once::h0567da839a080232 Jun 07 21:29:40 volumio volumio[1170]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/ops/function.rs:259:13 Jun 07 21:29:40 volumio volumio[1170]: 29: 0x9f7b28 - std::panicking::try::do_call::hdf194554a41aa3cc Jun 07 21:29:40 volumio volumio[1170]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:492:40 Jun 07 21:29:40 volumio volumio[1170]: 30: 0x9f7b28 - std::panicking::try::hbf4b7be9f9ffa5f8 Jun 07 21:29:40 volumio volumio[1170]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:456:19 Jun 07 21:29:40 volumio volumio[1170]: 31: 0x9f7b28 - std::panic::catch_unwind::h45f392a39f2fe48c Jun 07 21:29:40 volumio volumio[1170]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panic.rs:137:14 Jun 07 21:29:40 volumio volumio[1170]: 32: 0x9f7b28 - std::rt::lang_start_internal::{{closure}}::hd772cb03c2060671 Jun 07 21:29:40 volumio volumio[1170]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/rt.rs:128:48 Jun 07 21:29:40 volumio volumio[1170]: 33: 0x9f7b28 - std::panicking::try::do_call::hd126f715b519ff2a Jun 07 21:29:40 volumio volumio[1170]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:492:40 Jun 07 21:29:40 volumio volumio[1170]: 34: 0x9f7b28 - std::panicking::try::h80b7148ce71893dc Jun 07 21:29:40 volumio volumio[1170]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:456:19 Jun 07 21:29:40 volumio volumio[1170]: 35: 0x9f7b28 - std::panic::catch_unwind::h1562f0b2e8a9cb0d Jun 07 21:29:40 volumio volumio[1170]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panic.rs:137:14 Jun 07 21:29:40 volumio volumio[1170]: 36: 0x9f7b28 - std::rt::lang_start_internal::h3daa586a0b835507 Jun 07 21:29:40 volumio volumio[1170]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/rt.rs:128:20 Jun 07 21:29:40 volumio volumio[1170]: 37: 0x50ae80 - main Jun 07 21:29:40 volumio volumio[1170]: 38: 0x76ca0768 - __libc_start_main Jun 07 21:29:40 volumio systemd[1]: volspotconnect.service: Main process exited, code=exited, status=101/n/a Jun 07 21:29:40 volumio systemd[1]: volspotconnect.service: Failed with result 'exit-code'. Jun 07 21:29:42 volumio mpd[1179]: Jun 07 21:29 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 07 21:29:42 volumio mpd[1179]: Jun 07 21:29 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jun 07 21:29:42 volumio mpd[1179]: Jun 07 21:29 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jun 07 21:29:42 volumio systemd[1]: Started Music Player Daemon. Jun 07 21:29:42 volumio sudo[1149]: pam_unix(sudo:session): session closed for user root Jun 07 21:29:42 volumio volumio[779]: error: updateQueue error: null Jun 07 21:29:42 volumio systemd[1]: volspotconnect.service: Service RestartSec=2s expired, scheduling restart. Jun 07 21:29:42 volumio systemd[1]: volspotconnect.service: Scheduled restart job, restart counter is at 1. Jun 07 21:29:42 volumio systemd[1]: Stopped Volspotconnect2 Daemon. Jun 07 21:29:42 volumio systemd[1]: Started Volspotconnect2 Daemon. Jun 07 21:29:42 volumio volumio[1186]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 Jun 07 21:29:42 volumio volumio[1186]: Reading Config from "/tmp/volspotify.toml" Jun 07 21:29:42 volumio volumio[1186]: [Vollibrespot] : Using Alsa backend with device: volumio Jun 07 21:29:43 volumio volumio[1186]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" } Jun 07 21:29:43 volumio volumio[1186]: [Vollibrespot] : Connecting to AP "ap-guc3.spotify.com:443" Jun 07 21:29:43 volumio volumio[1186]: thread 'main' panicked at 'Authentication failed with reason: BadCredentials', /build/cache/git/checkouts/librespot-6f197fd632ef9380/08d8bcc/core/src/connection/mod.rs:93:21 Jun 07 21:29:43 volumio volumio[1186]: stack backtrace: Jun 07 21:29:43 volumio volumio[1186]: 0: 0xa28444 - std::backtrace_rs::backtrace::libunwind::trace::h40a4eff66330a36d Jun 07 21:29:43 volumio volumio[1186]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/../../backtrace/src/backtrace/libunwind.rs:93:5 Jun 07 21:29:43 volumio volumio[1186]: 1: 0xa28444 - std::backtrace_rs::backtrace::trace_unsynchronized::hbce01963451b0cd5 Jun 07 21:29:43 volumio volumio[1186]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 Jun 07 21:29:43 volumio volumio[1186]: 2: 0xa28444 - std::sys_common::backtrace::_print_fmt::h95da650d09c7e00b Jun 07 21:29:43 volumio volumio[1186]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:66:5 Jun 07 21:29:43 volumio volumio[1186]: 3: 0xa28444 - ::fmt::h33b23b1d14f668ff Jun 07 21:29:43 volumio volumio[1186]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:45:22 Jun 07 21:29:43 volumio volumio[1186]: 4: 0xa510dc - core::fmt::write::h6320fb0e6ac90e58 Jun 07 21:29:43 volumio volumio[1186]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/fmt/mod.rs:1190:17 Jun 07 21:29:43 volumio volumio[1186]: 5: 0xa204c4 - std::io::Write::write_fmt::h33b25d4810ca481f Jun 07 21:29:43 volumio volumio[1186]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/io/mod.rs:1657:15 Jun 07 21:29:43 volumio volumio[1186]: 6: 0xa2aef4 - std::sys_common::backtrace::_print::h7e61c5be69c715ef Jun 07 21:29:43 volumio volumio[1186]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:48:5 Jun 07 21:29:43 volumio volumio[1186]: 7: 0xa2aef4 - std::sys_common::backtrace::print::h3cee176d7c8dab8b Jun 07 21:29:43 volumio volumio[1186]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:35:9 Jun 07 21:29:43 volumio volumio[1186]: 8: 0xa2aef4 - std::panicking::default_hook::{{closure}}::h2d0211dd2e97dc43 Jun 07 21:29:43 volumio volumio[1186]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:295:22 Jun 07 21:29:43 volumio volumio[1186]: 9: 0xa2ab14 - std::panicking::default_hook::hcbdb3b7c40dca30c Jun 07 21:29:43 volumio volumio[1186]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:314:9 Jun 07 21:29:43 volumio volumio[1186]: 10: 0xa2b694 - std::panicking::rust_panic_with_hook::h4ce49e7a343dc12b Jun 07 21:29:43 volumio volumio[1186]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:698:17 Jun 07 21:29:43 volumio volumio[1186]: 11: 0xa2b304 - std::panicking::begin_panic_handler::{{closure}}::h4961100fae529fe9 Jun 07 21:29:43 volumio volumio[1186]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:588:13 Jun 07 21:29:43 volumio volumio[1186]: 12: 0xa289c4 - std::sys_common::backtrace::__rust_end_short_backtrace::hf2e68e62c7a80f1f Jun 07 21:29:43 volumio volumio[1186]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:138:18 Jun 07 21:29:43 volumio volumio[1186]: 13: 0xa2b098 - rust_begin_unwind Jun 07 21:29:43 volumio volumio[1186]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:584:5 Jun 07 21:29:43 volumio volumio[1186]: 14: 0x50e6f4 - core::panicking::panic_fmt::h84c42730e4a50809 Jun 07 21:29:43 volumio volumio[1186]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/panicking.rs:143:14 Jun 07 21:29:43 volumio volumio[1186]: 15: 0x6fa930 - futures::future::chain::Chain::poll::hb08394d341f2da5e Jun 07 21:29:43 volumio volumio[1186]: 16: 0x778d4c - as futures::future::Future>::poll::h0222f8b810b74ccb Jun 07 21:29:43 volumio volumio[1186]: 17: 0x6fb53c - futures::future::chain::Chain::poll::hd3215a5f3268adbc Jun 07 21:29:43 volumio volumio[1186]: 18: 0x7715f0 - as futures::future::Future>::poll::hc28eaa01d8d9029a Jun 07 21:29:43 volumio volumio[1186]: 19: 0x535b34 - ::poll::h5fd9e3bcdeb25ad9 Jun 07 21:29:43 volumio volumio[1186]: 20: 0x5533dc - futures::task_impl::std::set::h8f754e80bd562d0a Jun 07 21:29:43 volumio volumio[1186]: 21: 0x56a42c - std::thread::local::LocalKey::with::h71cfa1e374fcc0a0 Jun 07 21:29:43 volumio volumio[1186]: 22: 0x54f99c - tokio_current_thread::Entered

::block_on::hde55cdf28eabe6d5 Jun 07 21:29:43 volumio volumio[1186]: 23: 0x56a6f0 - std::thread::local::LocalKey::with::hedba3cfe41326627 Jun 07 21:29:43 volumio volumio[1186]: 24: 0x552880 - tokio::runtime::current_thread::runtime::Runtime::block_on::h60d5b89560100ed8 Jun 07 21:29:43 volumio volumio[1186]: 25: 0x538eb0 - vollibrespot::main::h35555355ed947dd1 Jun 07 21:29:43 volumio volumio[1186]: 26: 0x558c50 - std::sys_common::backtrace::__rust_begin_short_backtrace::hef626a10d324c12a Jun 07 21:29:43 volumio volumio[1186]: 27: 0x57e52c - std::rt::lang_start::{{closure}}::hd80188e4bcac5072 Jun 07 21:29:43 volumio volumio[1186]: 28: 0xa27b28 - core::ops::function::impls:: for &F>::call_once::h0567da839a080232 Jun 07 21:29:43 volumio volumio[1186]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/ops/function.rs:259:13 Jun 07 21:29:43 volumio volumio[1186]: 29: 0xa27b28 - std::panicking::try::do_call::hdf194554a41aa3cc Jun 07 21:29:43 volumio volumio[1186]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:492:40 Jun 07 21:29:43 volumio volumio[1186]: 30: 0xa27b28 - std::panicking::try::hbf4b7be9f9ffa5f8 Jun 07 21:29:43 volumio volumio[1186]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:456:19 Jun 07 21:29:43 volumio volumio[1186]: 31: 0xa27b28 - std::panic::catch_unwind::h45f392a39f2fe48c Jun 07 21:29:43 volumio volumio[1186]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panic.rs:137:14 Jun 07 21:29:43 volumio volumio[1186]: 32: 0xa27b28 - std::rt::lang_start_internal::{{closure}}::hd772cb03c2060671 Jun 07 21:29:43 volumio volumio[1186]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/rt.rs:128:48 Jun 07 21:29:43 volumio volumio[1186]: 33: 0xa27b28 - std::panicking::try::do_call::hd126f715b519ff2a Jun 07 21:29:43 volumio volumio[1186]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:492:40 Jun 07 21:29:43 volumio volumio[1186]: 34: 0xa27b28 - std::panicking::try::h80b7148ce71893dc Jun 07 21:29:43 volumio volumio[1186]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:456:19 Jun 07 21:29:43 volumio volumio[1186]: 35: 0xa27b28 - std::panic::catch_unwind::h1562f0b2e8a9cb0d Jun 07 21:29:43 volumio volumio[1186]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panic.rs:137:14 Jun 07 21:29:43 volumio volumio[1186]: 36: 0xa27b28 - std::rt::lang_start_internal::h3daa586a0b835507 Jun 07 21:29:43 volumio volumio[1186]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/rt.rs:128:20 Jun 07 21:29:43 volumio volumio[1186]: 37: 0x53ae80 - main Jun 07 21:29:43 volumio volumio[1186]: 38: 0x76bb6768 - __libc_start_main Jun 07 21:29:43 volumio systemd[1]: volspotconnect.service: Main process exited, code=exited, status=101/n/a Jun 07 21:29:43 volumio systemd[1]: volspotconnect.service: Failed with result 'exit-code'. Jun 07 21:29:43 volumio volumio[779]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jun 07 21:29:43 volumio volumio[779]: info: CoreStateMachine::getcurrentVolume Jun 07 21:29:43 volumio volumio[779]: info: CoreCommandRouter::volumioRetrievevolume Jun 07 21:29:43 volumio volumio[779]: info: CoreStateMachine::pushState Jun 07 21:29:43 volumio volumio[779]: info: CorePlayQueue::getTrack 16 Jun 07 21:29:43 volumio volumio[779]: info: CoreCommandRouter::volumioPushState Jun 07 21:29:43 volumio volumio[779]: info: CoreStateMachine::updateTrackBlock Jun 07 21:29:43 volumio volumio[779]: info: CorePlayQueue::getTrackBlock Jun 07 21:29:43 volumio volumio[779]: info: CoreCommandRouter::volumioRetrievevolume Jun 07 21:29:43 volumio volumio[779]: info: CoreStateMachine::pushState Jun 07 21:29:43 volumio volumio[779]: info: CorePlayQueue::getTrack 16 Jun 07 21:29:43 volumio volumio[779]: info: CoreCommandRouter::volumioPushState Jun 07 21:29:45 volumio systemd[1]: volspotconnect.service: Service RestartSec=2s expired, scheduling restart. Jun 07 21:29:45 volumio systemd[1]: volspotconnect.service: Scheduled restart job, restart counter is at 2. Jun 07 21:29:45 volumio systemd[1]: Stopped Volspotconnect2 Daemon. Jun 07 21:29:45 volumio systemd[1]: Started Volspotconnect2 Daemon. Jun 07 21:29:45 volumio volumio[1204]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 Jun 07 21:29:45 volumio volumio[1204]: Reading Config from "/tmp/volspotify.toml" Jun 07 21:29:45 volumio volumio[1204]: [Vollibrespot] : Using Alsa backend with device: volumio Jun 07 21:29:45 volumio volumio[1204]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" } Jun 07 21:29:45 volumio volumio[1204]: [Vollibrespot] : Connecting to AP "ap-guc3.spotify.com:443" Jun 07 21:29:45 volumio volumio[779]: info: Checking Spotify Web API Jun 07 21:29:45 volumio volumio[779]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 07 21:29:45 volumio volumio[779]: Error [ERR_SOCKET_DGRAM_NOT_RUNNING]: Not running Jun 07 21:29:45 volumio volumio[779]: at healthCheck (dgram.js:897:11) Jun 07 21:29:45 volumio volumio[779]: at Socket.send (dgram.js:622:3) Jun 07 21:29:45 volumio volumio[779]: at SpotConnEvents.sendmsg (/data/plugins/music_service/spop/SpotConnController.js:121:21) Jun 07 21:29:45 volumio volumio[779]: at ControllerSpotify.checkWebApi (/data/plugins/music_service/spop/index.js:2383:23) Jun 07 21:29:45 volumio volumio[779]: at Timeout._onTimeout (/data/plugins/music_service/spop/index.js:2723:18) Jun 07 21:29:45 volumio volumio[779]: at listOnTimeout (internal/timers.js:554:17) Jun 07 21:29:45 volumio volumio[779]: at processTimers (internal/timers.js:497:7) { Jun 07 21:29:45 volumio volumio[779]: code: 'ERR_SOCKET_DGRAM_NOT_RUNNING' Jun 07 21:29:45 volumio volumio[779]: } Jun 07 21:29:45 volumio volumio[779]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 07 21:29:46 volumio sudo[1219]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-07 21:28 Jun 07 21:29:46 volumio sudo[1219]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="b1c3cf61c2a0027c66bab1eb0a3795f80c1f2e95" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="e3eb3ee5b16063502f3a1735e11a28cfe54a0f46" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sun 25 Jun 2023 07:20:58 PM CEST" VOLUMIO_VERSION="3.512" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="946a42b62509f37b5e7e2d86a2932a62"