-- 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"