-- Logs begin at Sun 2025-10-26 08:24:54 +07, end at Sun 2025-10-26 08:37:23 +07. --
Oct 26 08:36:13 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Oct 26 08:36:13 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Oct 26 08:36:13 volumio volumio[909]: info: Discovery: Getting this device information
Oct 26 08:36:13 volumio volumio[909]: info: CoreCommandRouter::volumioGetState
Oct 26 08:36:13 volumio volumio[909]: info: CorePlayQueue::getTrack 0
Oct 26 08:36:13 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Oct 26 08:36:15 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Oct 26 08:36:15 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Oct 26 08:36:15 volumio volumio[909]: info: Discovery: Getting this device information
Oct 26 08:36:15 volumio volumio[909]: info: CoreCommandRouter::volumioGetState
Oct 26 08:36:15 volumio volumio[909]: info: CorePlayQueue::getTrack 0
Oct 26 08:36:15 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Oct 26 08:36:35 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Oct 26 08:36:35 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Oct 26 08:36:35 volumio volumio[909]: info: Discovery: Getting this device information
Oct 26 08:36:35 volumio volumio[909]: info: CoreCommandRouter::volumioGetState
Oct 26 08:36:35 volumio volumio[909]: info: CorePlayQueue::getTrack 0
Oct 26 08:36:35 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Oct 26 08:36:43 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Oct 26 08:36:43 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Oct 26 08:36:43 volumio volumio[909]: info: Discovery: Getting this device information
Oct 26 08:36:43 volumio volumio[909]: info: CoreCommandRouter::volumioGetState
Oct 26 08:36:43 volumio volumio[909]: info: CorePlayQueue::getTrack 0
Oct 26 08:36:43 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Oct 26 08:36:55 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Oct 26 08:36:55 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Oct 26 08:36:55 volumio volumio[909]: info: Discovery: Getting this device information
Oct 26 08:36:55 volumio volumio[909]: info: CoreCommandRouter::volumioGetState
Oct 26 08:36:55 volumio volumio[909]: info: CorePlayQueue::getTrack 0
Oct 26 08:36:55 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Oct 26 08:37:08 volumio volumio[909]: info: Enabling plugin fusiondsp
Oct 26 08:37:08 volumio volumio[909]: info: Loading plugin "fusiondsp"...
Oct 26 08:37:08 volumio volumio[909]: info: Preparing to generate the ALSA configuration file
Oct 26 08:37:08 volumio volumio[909]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf
Oct 26 08:37:08 volumio volumio[909]: info: Reading ALSA contributions from plugins.
Oct 26 08:37:08 volumio volumio[909]: info: Asound.conf file written
Oct 26 08:37:08 volumio sudo[5972]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf
Oct 26 08:37:08 volumio sudo[5972]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 26 08:37:08 volumio sudo[5972]: pam_unix(sudo:session): session closed for user root
Oct 26 08:37:08 volumio volumio[909]: info: Output device has changed, restarting MPD
Oct 26 08:37:08 volumio sudo[5978]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Oct 26 08:37:08 volumio sudo[5978]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 26 08:37:08 volumio sudo[5978]: pam_unix(sudo:session): session closed for user root
Oct 26 08:37:08 volumio volumio[909]: info: Output device has changed, restarting Shairport Sync
Oct 26 08:37:08 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:09 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 26 08:37:09 volumio sudo[5981]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Oct 26 08:37:09 volumio sudo[5981]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 26 08:37:09 volumio volumio[909]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Oct 26 08:37:09 volumio volumio[909]: [SpotifyConnect] Creating VLS config file
Oct 26 08:37:09 volumio systemd[1]: Stopping Music Player Daemon...
Oct 26 08:37:09 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:09 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:09 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:09 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:09 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:09 volumio volumio[909]: info: PLUGIN START: fusiondsp
Oct 26 08:37:09 volumio volumio[909]: info: Loading i18n strings for locale vi
Oct 26 08:37:09 volumio volumio[909]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found.
Oct 26 08:37:09 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile
Oct 26 08:37:09 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:09 volumio volumio[909]: info: FusionDsp -  mixtype--------------------- Hardware
Oct 26 08:37:09 volumio volumio[909]: info: Preparing to generate the ALSA configuration file
Oct 26 08:37:09 volumio volumio[909]: info: Done.
Oct 26 08:37:09 volumio volumio[909]: [SpotifyConnect] Restarting Vollibrespot Daemon
Oct 26 08:37:09 volumio volumio[909]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf
Oct 26 08:37:09 volumio volumio[909]: info: Reading ALSA contributions from plugins.
Oct 26 08:37:09 volumio volumio[909]: info: MPD Permissions set
Oct 26 08:37:09 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:09 volumio sudo[5987]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service
Oct 26 08:37:09 volumio sudo[5987]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 26 08:37:09 volumio systemd[1]: Stopping Volspotconnect2 Daemon...
Oct 26 08:37:09 volumio systemd[1]: volspotconnect.service: Main process exited, code=killed, status=15/TERM
Oct 26 08:37:09 volumio systemd[1]: volspotconnect.service: Succeeded.
Oct 26 08:37:09 volumio systemd[1]: Stopped Volspotconnect2 Daemon.
Oct 26 08:37:09 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 26 08:37:09 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber
Oct 26 08:37:09 volumio systemd[1]: Started Volspotconnect2 Daemon.
Oct 26 08:37:09 volumio sudo[5987]: pam_unix(sudo:session): session closed for user root
Oct 26 08:37:09 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:09 volumio volumio[909]: info: CoreCommandRouter::getUIConfigOnPlugin
Oct 26 08:37:09 volumio volumio[5993]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31
Oct 26 08:37:09 volumio volumio[909]: info: CoreCommandRouter::getUIConfigOnPlugin
Oct 26 08:37:09 volumio volumio[5993]: Reading Config from "/tmp/volspotify.toml"
Oct 26 08:37:09 volumio volumio[909]: [SpotifyConnect] Vollibrespot Daemon service restarted!
Oct 26 08:37:09 volumio volumio[909]: info: Asound.conf file unchanged, so no further update is needed
Oct 26 08:37:09 volumio volumio[909]: info: Output device has changed, restarting MPD
Oct 26 08:37:09 volumio volumio[5993]: [Vollibrespot] : Using Alsa backend with device: volumio
Oct 26 08:37:09 volumio volumio[5993]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" }
Oct 26 08:37:09 volumio sudo[5999]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Oct 26 08:37:09 volumio sudo[5999]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 26 08:37:09 volumio sudo[5999]: pam_unix(sudo:session): session closed for user root
Oct 26 08:37:09 volumio volumio[909]: info: Output device has changed, restarting Shairport Sync
Oct 26 08:37:09 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:09 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 26 08:37:09 volumio sudo[6006]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Oct 26 08:37:09 volumio sudo[6006]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 26 08:37:09 volumio volumio[909]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Oct 26 08:37:09 volumio volumio[909]: [SpotifyConnect] Creating VLS config file
Oct 26 08:37:09 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:09 volumio volumio[909]: [SpotifyConnect] Restarting Vollibrespot Daemon
Oct 26 08:37:09 volumio volumio[5993]: [Vollibrespot] : Connecting to AP "ap-gae2.spotify.com:443"
Oct 26 08:37:09 volumio volumio[909]: info: Starting Shairport Sync
Oct 26 08:37:09 volumio sudo[6012]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service
Oct 26 08:37:09 volumio sudo[6012]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 26 08:37:09 volumio systemd[1]: mpd.service: Succeeded.
Oct 26 08:37:09 volumio systemd[1]: Stopped Music Player Daemon.
Oct 26 08:37:09 volumio volumio[909]: info: MPD Permissions set
Oct 26 08:37:09 volumio systemd[1]: Stopping Volspotconnect2 Daemon...
Oct 26 08:37:09 volumio volumio[909]: info: FusionDsp -
Oct 26 08:37:09 volumio sudo[6015]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Oct 26 08:37:09 volumio sudo[6015]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 26 08:37:09 volumio systemd[1]: Starting Music Player Daemon...
Oct 26 08:37:09 volumio systemd[1]: volspotconnect.service: Main process exited, code=killed, status=15/TERM
Oct 26 08:37:09 volumio systemd[1]: volspotconnect.service: Succeeded.
Oct 26 08:37:09 volumio systemd[1]: Stopped Volspotconnect2 Daemon.
Oct 26 08:37:09 volumio systemd[1]: Started Volspotconnect2 Daemon.
Oct 26 08:37:09 volumio sudo[6012]: pam_unix(sudo:session): session closed for user root
Oct 26 08:37:09 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Oct 26 08:37:09 volumio volumio[6019]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31
Oct 26 08:37:09 volumio volumio[6019]: Reading Config from "/tmp/volspotify.toml"
Oct 26 08:37:09 volumio volumio[6019]: [Vollibrespot] : Using Alsa backend with device: volumio
Oct 26 08:37:09 volumio systemd[1]: shairport-sync.service: Succeeded.
Oct 26 08:37:09 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Oct 26 08:37:09 volumio volumio[6019]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" }
Oct 26 08:37:09 volumio sudo[6016]:     root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Oct 26 08:37:09 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Oct 26 08:37:09 volumio sudo[6016]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 26 08:37:09 volumio sudo[6015]: pam_unix(sudo:session): session closed for user root
Oct 26 08:37:09 volumio sudo[6016]: pam_unix(sudo:session): session closed for user root
Oct 26 08:37:10 volumio volumio[6019]: [Vollibrespot] : Connecting to AP "ap-gae2.spotify.com:443"
Oct 26 08:37:10 volumio volumio[909]: info: FusionDsp -
Oct 26 08:37:10 volumio volumio[909]: info: FusionDsp - undefined
Oct 26 08:37:10 volumio volumio[909]: info: FusionDsp - undefined
Oct 26 08:37:10 volumio volumio[6019]: thread 'main' panicked at 'Authentication failed with reason: BadCredentials', /build/cache/git/checkouts/librespot-6f197fd632ef9380/08d8bcc/core/src/connection/mod.rs:93:21
Oct 26 08:37:10 volumio volumio[6019]: stack backtrace:
Oct 26 08:37:10 volumio volumio[909]: info: Shairport-Sync Started
Oct 26 08:37:10 volumio volumio[909]: [SpotifyConnect] Vollibrespot Daemon service restarted!
Oct 26 08:37:10 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:10 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:10 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:10 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:10 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:10 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:10 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:10 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:10 volumio volumio[6019]:    0:     0x562bf92600cd - std::backtrace_rs::backtrace::libunwind::trace::hee598835bc88d35b
Oct 26 08:37:10 volumio volumio[6019]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/../../backtrace/src/backtrace/libunwind.rs:93:5
Oct 26 08:37:10 volumio volumio[6019]:    1:     0x562bf92600cd - std::backtrace_rs::backtrace::trace_unsynchronized::h9cdc730ba5cf5d72
Oct 26 08:37:10 volumio volumio[6019]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5
Oct 26 08:37:10 volumio volumio[6019]:    2:     0x562bf92600cd - std::sys_common::backtrace::_print_fmt::h75aeaf7ed30e43fa
Oct 26 08:37:10 volumio volumio[6019]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:66:5
Oct 26 08:37:10 volumio volumio[6019]:    3:     0x562bf92600cd - ::fmt::h606862f787600875
Oct 26 08:37:10 volumio volumio[6019]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:45:22
Oct 26 08:37:10 volumio volumio[6019]:    4:     0x562bf9285f5c - core::fmt::write::he803f0f418caf762
Oct 26 08:37:10 volumio volumio[6019]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/fmt/mod.rs:1190:17
Oct 26 08:37:10 volumio volumio[6019]:    5:     0x562bf9258cb8 - std::io::Write::write_fmt::h70bc45872f37e7bb
Oct 26 08:37:10 volumio volumio[6019]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/io/mod.rs:1657:15
Oct 26 08:37:10 volumio volumio[6019]:    6:     0x562bf92625a7 - std::sys_common::backtrace::_print::h64d038cf8ac3e13e
Oct 26 08:37:10 volumio volumio[6019]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:48:5
Oct 26 08:37:10 volumio volumio[6019]:    7:     0x562bf92625a7 - std::sys_common::backtrace::print::h359300b4a7fccf65
Oct 26 08:37:10 volumio volumio[6019]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:35:9
Oct 26 08:37:10 volumio volumio[6019]:    8:     0x562bf92625a7 - std::panicking::default_hook::{{closure}}::hf51be35e2f510149
Oct 26 08:37:10 volumio volumio[6019]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:295:22
Oct 26 08:37:10 volumio volumio[6019]:    9:     0x562bf9262270 - std::panicking::default_hook::h03ca0f22e1d2d25e
Oct 26 08:37:10 volumio volumio[6019]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:314:9
Oct 26 08:37:10 volumio volumio[6019]:   10:     0x562bf9262cf9 - std::panicking::rust_panic_with_hook::h3b7380e99b825b63
Oct 26 08:37:10 volumio volumio[6019]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:698:17
Oct 26 08:37:10 volumio volumio[6019]:   11:     0x562bf92629e7 - std::panicking::begin_panic_handler::{{closure}}::h8e849d0710154ce0
Oct 26 08:37:10 volumio volumio[6019]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:588:13
Oct 26 08:37:10 volumio volumio[6019]:   12:     0x562bf9260594 - std::sys_common::backtrace::__rust_end_short_backtrace::hedcdaddbd4c46cc5
Oct 26 08:37:10 volumio volumio[6019]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:138:18
Oct 26 08:37:10 volumio volumio[6019]:   13:     0x562bf92626f9 - rust_begin_unwind
Oct 26 08:37:10 volumio volumio[6019]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:584:5
Oct 26 08:37:10 volumio volumio[6019]:   14:     0x562bf8d6bf53 - core::panicking::panic_fmt::he1bbc7336d49a357
Oct 26 08:37:10 volumio volumio[6019]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/panicking.rs:143:14
Oct 26 08:37:10 volumio volumio[6019]:   15:     0x562bf8f3bf56 - futures::future::chain::Chain::poll::hf0e729e3d4d8df0d
Oct 26 08:37:10 volumio volumio[6019]:   16:     0x562bf8faea19 -  as futures::future::Future>::poll::hb2bebc352335cc1a
Oct 26 08:37:10 volumio volumio[6019]:   17:     0x562bf8f3a8e6 - futures::future::chain::Chain::poll::hda5263cfb178296b
Oct 26 08:37:10 volumio volumio[6019]:   18:     0x562bf8faffe1 -  as futures::future::Future>::poll::h7445370ea1c2ee99
Oct 26 08:37:10 volumio volumio[6019]:   19:     0x562bf8d99736 - ::poll::hdecdb1a04917de4b
Oct 26 08:37:10 volumio volumio[6019]:   20:     0x562bf8db1149 - futures::task_impl::std::set::h6dd4b73f744a06d4
Oct 26 08:37:10 volumio volumio[6019]:   21:     0x562bf8dc73bd - std::thread::local::LocalKey::with::hc9bf2e930c1fdc36
Oct 26 08:37:10 volumio volumio[6019]:   22:     0x562bf8dadc56 - tokio_current_thread::Entered::block_on::h372d7b837fe25d26
Oct 26 08:37:10 volumio volumio[6019]:   23:     0x562bf8dc6b48 - std::thread::local::LocalKey::with::h247079172a6ad00b
Oct 26 08:37:10 volumio volumio[6019]:   24:     0x562bf8db08c5 - tokio::runtime::current_thread::runtime::Runtime::block_on::h538e89536cb974b3
Oct 26 08:37:10 volumio volumio[6019]:   25:     0x562bf8d9b7e9 - vollibrespot::main::hf67292b633164777
Oct 26 08:37:10 volumio volumio[6019]:   26:     0x562bf8db5c73 - std::sys_common::backtrace::__rust_begin_short_backtrace::h9396fe1ba8d4dd7f
Oct 26 08:37:10 volumio volumio[6019]:   27:     0x562bf8dd8ce9 - std::rt::lang_start::{{closure}}::h0862538761d46dbc
Oct 26 08:37:10 volumio volumio[6019]:   28:     0x562bf925f7b1 - core::ops::function::impls:: for &F>::call_once::hb7014f43484a8b4e
Oct 26 08:37:10 volumio volumio[6019]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/ops/function.rs:259:13
Oct 26 08:37:10 volumio volumio[6019]:   29:     0x562bf925f7b1 - std::panicking::try::do_call::h7bc9dc436daeb8c7
Oct 26 08:37:10 volumio volumio[6019]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:492:40
Oct 26 08:37:10 volumio volumio[6019]:   30:     0x562bf925f7b1 - std::panicking::try::h653d68a27ff5f175
Oct 26 08:37:10 volumio volumio[6019]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:456:19
Oct 26 08:37:10 volumio volumio[6019]:   31:     0x562bf925f7b1 - std::panic::catch_unwind::h9d739f9f59895e68
Oct 26 08:37:10 volumio volumio[6019]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panic.rs:137:14
Oct 26 08:37:10 volumio volumio[6019]:   32:     0x562bf925f7b1 - std::rt::lang_start_internal::{{closure}}::hf006f2bc7ce22bbe
Oct 26 08:37:10 volumio volumio[6019]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/rt.rs:128:48
Oct 26 08:37:10 volumio volumio[6019]:   33:     0x562bf925f7b1 - std::panicking::try::do_call::hfb39d6df61a2e69f
Oct 26 08:37:10 volumio volumio[6019]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:492:40
Oct 26 08:37:10 volumio volumio[6019]:   34:     0x562bf925f7b1 - std::panicking::try::h13e2d225134958ac
Oct 26 08:37:10 volumio volumio[6019]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:456:19
Oct 26 08:37:10 volumio volumio[6019]:   35:     0x562bf925f7b1 - std::panic::catch_unwind::h3bd49b5a5dfb1a50
Oct 26 08:37:10 volumio volumio[6019]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panic.rs:137:14
Oct 26 08:37:10 volumio volumio[6019]:   36:     0x562bf925f7b1 - std::rt::lang_start_internal::h2ba92edce36c035e
Oct 26 08:37:10 volumio volumio[6019]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/rt.rs:128:20
Oct 26 08:37:10 volumio volumio[6019]:   37:     0x562bf8d9e562 - main
Oct 26 08:37:10 volumio volumio[6019]:   38:     0x7fd98828509b - __libc_start_main
Oct 26 08:37:10 volumio volumio[6019]:   39:     0x562bf8d6c78a - _start
Oct 26 08:37:10 volumio volumio[6019]:   40:                0x0 - 
Oct 26 08:37:10 volumio systemd[1]: volspotconnect.service: Main process exited, code=exited, status=101/n/a
Oct 26 08:37:10 volumio systemd[1]: volspotconnect.service: Failed with result 'exit-code'.
Oct 26 08:37:10 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 26 08:37:10 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber
Oct 26 08:37:10 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:10 volumio volumio[909]: info: Starting Shairport Sync
Oct 26 08:37:10 volumio volumio[909]: info: Enabling plugin fusiondsp
Oct 26 08:37:10 volumio volumio[909]: info: Loading plugin "fusiondsp"...
Oct 26 08:37:10 volumio volumio[909]: info: Applying required configuration parameters for plugin fusiondsp
Oct 26 08:37:10 volumio volumio[909]: info: Preparing to generate the ALSA configuration file
Oct 26 08:37:10 volumio sudo[6037]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Oct 26 08:37:10 volumio sudo[6037]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 26 08:37:10 volumio volumio[909]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf
Oct 26 08:37:10 volumio volumio[909]: info: Reading ALSA contributions from plugins.
Oct 26 08:37:11 volumio volumio[909]: info: Asound.conf file unchanged, so no further update is needed
Oct 26 08:37:11 volumio volumio[909]: info: Output device has changed, restarting MPD
Oct 26 08:37:11 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Oct 26 08:37:11 volumio systemd[1]: shairport-sync.service: Succeeded.
Oct 26 08:37:11 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Oct 26 08:37:11 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Oct 26 08:37:11 volumio sudo[6037]: pam_unix(sudo:session): session closed for user root
Oct 26 08:37:11 volumio sudo[6041]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Oct 26 08:37:11 volumio sudo[6041]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 26 08:37:11 volumio sudo[6041]: pam_unix(sudo:session): session closed for user root
Oct 26 08:37:11 volumio volumio[909]: info: Output device has changed, restarting Shairport Sync
Oct 26 08:37:11 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:11 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 26 08:37:11 volumio sudo[6046]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Oct 26 08:37:11 volumio sudo[6046]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 26 08:37:11 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM
Oct 26 08:37:11 volumio systemd[1]: mpd.service: Succeeded.
Oct 26 08:37:11 volumio systemd[1]: Stopped Music Player Daemon.
Oct 26 08:37:11 volumio volumio[909]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Oct 26 08:37:11 volumio volumio[909]: [SpotifyConnect] Creating VLS config file
Oct 26 08:37:11 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:11 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:11 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:11 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:11 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:11 volumio volumio[909]: info: PLUGIN START: fusiondsp
Oct 26 08:37:11 volumio volumio[909]: info: Loading i18n strings for locale vi
Oct 26 08:37:11 volumio systemd[1]: Starting Music Player Daemon...
Oct 26 08:37:11 volumio volumio[909]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found.
Oct 26 08:37:11 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile
Oct 26 08:37:11 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:11 volumio volumio[909]: info: FusionDsp -  mixtype--------------------- Hardware
Oct 26 08:37:11 volumio volumio[909]: info: Preparing to generate the ALSA configuration file
Oct 26 08:37:11 volumio volumio[909]: info: Done.
Oct 26 08:37:11 volumio volumio[909]: [SpotifyConnect] Restarting Vollibrespot Daemon
Oct 26 08:37:11 volumio sudo[6054]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service
Oct 26 08:37:11 volumio sudo[6051]:     root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Oct 26 08:37:11 volumio sudo[6054]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 26 08:37:11 volumio sudo[6051]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 26 08:37:11 volumio sudo[6051]: pam_unix(sudo:session): session closed for user root
Oct 26 08:37:11 volumio systemd[1]: Stopped Volspotconnect2 Daemon.
Oct 26 08:37:11 volumio systemd[1]: Started Volspotconnect2 Daemon.
Oct 26 08:37:11 volumio sudo[6054]: pam_unix(sudo:session): session closed for user root
Oct 26 08:37:11 volumio volumio[909]: info: camilladsp service started and running in background, instance 1
Oct 26 08:37:11 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:11 volumio volumio[6065]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31
Oct 26 08:37:11 volumio volumio[6065]: Reading Config from "/tmp/volspotify.toml"
Oct 26 08:37:11 volumio volumio[6065]: Malformed config key: missing field `Authentication`
Oct 26 08:37:11 volumio systemd[1]: volspotconnect.service: Main process exited, code=exited, status=1/FAILURE
Oct 26 08:37:11 volumio systemd[1]: volspotconnect.service: Failed with result 'exit-code'.
Oct 26 08:37:11 volumio volumio[909]: info: FusionDsp -  AAAAAAAAAAAAAA->  44100 48000 88200 96000 176400 192000 384000 768000 <-AAAAAAAAAAAAA
Oct 26 08:37:11 volumio volumio[909]: info: FusionDsp loaded
Oct 26 08:37:11 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Oct 26 08:37:11 volumio sudo[6072]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service
Oct 26 08:37:11 volumio sudo[6072]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 26 08:37:11 volumio systemd[1]: Started FusionDsp Daemon.
Oct 26 08:37:11 volumio sudo[6072]: pam_unix(sudo:session): session closed for user root
Oct 26 08:37:11 volumio volumio[909]: info: FusionDsp -  Reporting Fusion DSP Enabled
Oct 26 08:37:11 volumio volumio[909]: info: Adding Signal Path Element [object Object]
Oct 26 08:37:11 volumio volumio[909]: info: Adding fusiondspeq DSP Signal Path Element
Oct 26 08:37:11 volumio volumio[909]: info: FusionDsp -  ---- installed callbackRead
Oct 26 08:37:11 volumio volumio[909]: info: FusionDsp -  If filter freq >samplerate/2 then disable it
Oct 26 08:37:11 volumio volumio[909]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf
Oct 26 08:37:11 volumio volumio[909]: info: Reading ALSA contributions from plugins.
Oct 26 08:37:11 volumio volumio[909]: info: CoreCommandRouter::getUIConfigOnPlugin
Oct 26 08:37:11 volumio volumio[909]: info: CoreCommandRouter::getUIConfigOnPlugin
Oct 26 08:37:11 volumio volumio[909]: info: Enabling plugin fusiondsp
Oct 26 08:37:11 volumio volumio[909]: info: Loading plugin "fusiondsp"...
Oct 26 08:37:11 volumio volumio[909]: info: Applying required configuration parameters for plugin fusiondsp
Oct 26 08:37:11 volumio volumio[909]: [SpotifyConnect] Vollibrespot Daemon service restarted!
Oct 26 08:37:11 volumio volumio[909]: info: MPD Permissions set
Oct 26 08:37:11 volumio volumio[909]: info: Shairport-Sync Started
Oct 26 08:37:11 volumio volumio[909]: error: FusionDsp - WebSocket error: [object Object]
Oct 26 08:37:11 volumio volumio[909]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9
Oct 26 08:37:11 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:11 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:11 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:11 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:11 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:11 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:11 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:12 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 26 08:37:12 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber
Oct 26 08:37:12 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:12 volumio volumio[909]: info: FusionDsp -
Oct 26 08:37:12 volumio volumio[909]: info: FusionDsp -
Oct 26 08:37:12 volumio volumio[909]: info: Enabling plugin fusiondsp
Oct 26 08:37:12 volumio volumio[909]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/amd64
Oct 26 08:37:13 volumio volumio[909]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/amd64
Oct 26 08:37:13 volumio volumio[909]: info: Loading plugin "fusiondsp"...
Oct 26 08:37:13 volumio volumio[909]: info: Applying required configuration parameters for plugin fusiondsp
Oct 26 08:37:13 volumio mpd[6063]: Oct 26 08:37 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Oct 26 08:37:13 volumio systemd[1]: Started Music Player Daemon.
Oct 26 08:37:13 volumio sudo[6006]: pam_unix(sudo:session): session closed for user root
Oct 26 08:37:13 volumio sudo[6046]: pam_unix(sudo:session): session closed for user root
Oct 26 08:37:13 volumio sudo[5981]: pam_unix(sudo:session): session closed for user root
Oct 26 08:37:13 volumio volumio[909]: 0+0 records in
Oct 26 08:37:13 volumio volumio[909]: 0+0 records out
Oct 26 08:37:13 volumio volumio[909]: 0 bytes copied, 0.00311816 s, 0.0 kB/s
Oct 26 08:37:13 volumio volumio[909]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists
Oct 26 08:37:13 volumio volumio[909]: error: FusionDsp -  ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo
Oct 26 08:37:13 volumio volumio[909]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists
Oct 26 08:37:13 volumio systemd[1]: volspotconnect.service: Service RestartSec=2s expired, scheduling restart.
Oct 26 08:37:13 volumio systemd[1]: volspotconnect.service: Scheduled restart job, restart counter is at 1.
Oct 26 08:37:13 volumio systemd[1]: Stopped Volspotconnect2 Daemon.
Oct 26 08:37:13 volumio systemd[1]: Started Volspotconnect2 Daemon.
Oct 26 08:37:13 volumio volumio[909]: info: camilladsp service started and running in background, instance 2
Oct 26 08:37:13 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:13 volumio volumio[6102]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31
Oct 26 08:37:13 volumio volumio[6102]: Reading Config from "/tmp/volspotify.toml"
Oct 26 08:37:13 volumio volumio[6102]: [Vollibrespot] : Using Alsa backend with device: volumio
Oct 26 08:37:13 volumio volumio[6102]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" }
Oct 26 08:37:13 volumio volumio[909]: cannot open device 'volumioHw': Device or resource busy
Oct 26 08:37:13 volumio volumio[909]: error: FusionDsp -  ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json
Oct 26 08:37:13 volumio volumio[909]: cannot open device 'volumioHw': Device or resource busy
Oct 26 08:37:13 volumio volumio[909]: info: FusionDsp loaded
Oct 26 08:37:13 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Oct 26 08:37:13 volumio volumio[909]: info: FusionDsp -  Reporting Fusion DSP Enabled
Oct 26 08:37:13 volumio volumio[909]: info: Adding Signal Path Element [object Object]
Oct 26 08:37:13 volumio volumio[909]: info: Updating fusiondspeq DSP Signal Path Element
Oct 26 08:37:13 volumio volumio[909]: info: FusionDsp -  ---- installed callbackRead
Oct 26 08:37:13 volumio volumio[909]: info: FusionDsp -  If filter freq >samplerate/2 then disable it
Oct 26 08:37:13 volumio sudo[6118]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service
Oct 26 08:37:13 volumio sudo[6118]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 26 08:37:13 volumio volumio[909]: info: FusionDsp - undefined
Oct 26 08:37:13 volumio sudo[6118]: pam_unix(sudo:session): session closed for user root
Oct 26 08:37:13 volumio volumio[909]: info: FusionDsp - undefined
Oct 26 08:37:13 volumio volumio[6102]: [Vollibrespot] : Connecting to AP "ap-gae2.spotify.com:443"
Oct 26 08:37:13 volumio volumio[909]: info: FusionDsp -  ---- read samplerate, raw:
Oct 26 08:37:13 volumio volumio[909]: error: FusionDsp - invalid sample rate
Oct 26 08:37:13 volumio volumio[909]: info: FusionDsp -  ---- read samplerate, raw:
Oct 26 08:37:13 volumio volumio[909]: error: FusionDsp - invalid sample rate
Oct 26 08:37:13 volumio volumio[909]: info: Starting Shairport Sync
Oct 26 08:37:14 volumio volumio[909]: error: MPD error: The expression evaluated to a falsy value:
Oct 26 08:37:14 volumio volumio[909]:   assert.ok(self.idling)
Oct 26 08:37:14 volumio volumio[909]: error: The expression evaluated to a falsy value:
Oct 26 08:37:14 volumio volumio[909]:   assert.ok(self.idling)
Oct 26 08:37:14 volumio volumio[909]: error: MPD error: The expression evaluated to a falsy value:
Oct 26 08:37:14 volumio volumio[909]:   assert.ok(self.idling)
Oct 26 08:37:14 volumio volumio[909]: error: The expression evaluated to a falsy value:
Oct 26 08:37:14 volumio volumio[909]:   assert.ok(self.idling)
Oct 26 08:37:14 volumio volumio[909]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10
Oct 26 08:37:14 volumio sudo[6132]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Oct 26 08:37:14 volumio sudo[6132]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 26 08:37:14 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Oct 26 08:37:14 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Oct 26 08:37:14 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Oct 26 08:37:14 volumio volumio[909]: info: Discovery: Getting this device information
Oct 26 08:37:14 volumio volumio[909]: info: CoreCommandRouter::volumioGetState
Oct 26 08:37:14 volumio volumio[909]: info: CorePlayQueue::getTrack 0
Oct 26 08:37:14 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Oct 26 08:37:14 volumio systemd[1]: shairport-sync.service: Succeeded.
Oct 26 08:37:14 volumio volumio[909]: info: Asound.conf file unchanged, so no further update is needed
Oct 26 08:37:14 volumio volumio[909]: info: Output device has changed, restarting MPD
Oct 26 08:37:14 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Oct 26 08:37:14 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Oct 26 08:37:14 volumio sudo[6132]: pam_unix(sudo:session): session closed for user root
Oct 26 08:37:14 volumio sudo[6146]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Oct 26 08:37:14 volumio sudo[6146]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 26 08:37:14 volumio sudo[6146]: pam_unix(sudo:session): session closed for user root
Oct 26 08:37:14 volumio volumio[909]: info: Output device has changed, restarting Shairport Sync
Oct 26 08:37:14 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:14 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 26 08:37:14 volumio sudo[6150]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Oct 26 08:37:14 volumio sudo[6150]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 26 08:37:14 volumio systemd[1]: Stopping Music Player Daemon...
Oct 26 08:37:14 volumio volumio[6102]: thread 'main' panicked at 'Authentication failed with reason: BadCredentials', /build/cache/git/checkouts/librespot-6f197fd632ef9380/08d8bcc/core/src/connection/mod.rs:93:21
Oct 26 08:37:14 volumio volumio[6102]: stack backtrace:
Oct 26 08:37:14 volumio systemd[1]: mpd.service: Succeeded.
Oct 26 08:37:14 volumio systemd[1]: Stopped Music Player Daemon.
Oct 26 08:37:14 volumio volumio[6102]:    0:     0x558f80bb90cd - std::backtrace_rs::backtrace::libunwind::trace::hee598835bc88d35b
Oct 26 08:37:14 volumio volumio[6102]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/../../backtrace/src/backtrace/libunwind.rs:93:5
Oct 26 08:37:14 volumio volumio[909]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Oct 26 08:37:14 volumio volumio[909]: [SpotifyConnect] Creating VLS config file
Oct 26 08:37:14 volumio systemd[1]: Starting Music Player Daemon...
Oct 26 08:37:14 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:14 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:14 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:14 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:14 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:14 volumio volumio[909]: info: Preparing to generate the ALSA configuration file
Oct 26 08:37:14 volumio volumio[6102]:    1:     0x558f80bb90cd - std::backtrace_rs::backtrace::trace_unsynchronized::h9cdc730ba5cf5d72
Oct 26 08:37:14 volumio volumio[6102]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5
Oct 26 08:37:14 volumio volumio[6102]:    2:     0x558f80bb90cd - std::sys_common::backtrace::_print_fmt::h75aeaf7ed30e43fa
Oct 26 08:37:14 volumio volumio[6102]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:66:5
Oct 26 08:37:14 volumio volumio[6102]:    3:     0x558f80bb90cd - ::fmt::h606862f787600875
Oct 26 08:37:14 volumio volumio[6102]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:45:22
Oct 26 08:37:14 volumio volumio[6102]:    4:     0x558f80bdef5c - core::fmt::write::he803f0f418caf762
Oct 26 08:37:14 volumio volumio[6102]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/fmt/mod.rs:1190:17
Oct 26 08:37:14 volumio volumio[6102]:    5:     0x558f80bb1cb8 - std::io::Write::write_fmt::h70bc45872f37e7bb
Oct 26 08:37:14 volumio volumio[6102]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/io/mod.rs:1657:15
Oct 26 08:37:14 volumio volumio[6102]:    6:     0x558f80bbb5a7 - std::sys_common::backtrace::_print::h64d038cf8ac3e13e
Oct 26 08:37:14 volumio volumio[6102]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:48:5
Oct 26 08:37:14 volumio volumio[6102]:    7:     0x558f80bbb5a7 - std::sys_common::backtrace::print::h359300b4a7fccf65
Oct 26 08:37:14 volumio volumio[6102]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:35:9
Oct 26 08:37:14 volumio volumio[6102]:    8:     0x558f80bbb5a7 - std::panicking::default_hook::{{closure}}::hf51be35e2f510149
Oct 26 08:37:14 volumio volumio[6102]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:295:22
Oct 26 08:37:14 volumio volumio[6102]:    9:     0x558f80bbb270 - std::panicking::default_hook::h03ca0f22e1d2d25e
Oct 26 08:37:14 volumio volumio[6102]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:314:9
Oct 26 08:37:14 volumio volumio[6102]:   10:     0x558f80bbbcf9 - std::panicking::rust_panic_with_hook::h3b7380e99b825b63
Oct 26 08:37:14 volumio volumio[6102]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:698:17
Oct 26 08:37:14 volumio volumio[6102]:   11:     0x558f80bbb9e7 - std::panicking::begin_panic_handler::{{closure}}::h8e849d0710154ce0
Oct 26 08:37:14 volumio volumio[6102]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:588:13
Oct 26 08:37:14 volumio volumio[6102]:   12:     0x558f80bb9594 - std::sys_common::backtrace::__rust_end_short_backtrace::hedcdaddbd4c46cc5
Oct 26 08:37:14 volumio volumio[6102]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:138:18
Oct 26 08:37:14 volumio volumio[6102]:   13:     0x558f80bbb6f9 - rust_begin_unwind
Oct 26 08:37:14 volumio volumio[6102]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:584:5
Oct 26 08:37:14 volumio volumio[6102]:   14:     0x558f806c4f53 - core::panicking::panic_fmt::he1bbc7336d49a357
Oct 26 08:37:14 volumio volumio[6102]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/panicking.rs:143:14
Oct 26 08:37:14 volumio volumio[6102]:   15:     0x558f80894f56 - futures::future::chain::Chain::poll::hf0e729e3d4d8df0d
Oct 26 08:37:14 volumio volumio[6102]:   16:     0x558f80907a19 -  as futures::future::Future>::poll::hb2bebc352335cc1a
Oct 26 08:37:14 volumio volumio[6102]:   17:     0x558f808938e6 - futures::future::chain::Chain::poll::hda5263cfb178296b
Oct 26 08:37:14 volumio volumio[6102]:   18:     0x558f80908fe1 -  as futures::future::Future>::poll::h7445370ea1c2ee99
Oct 26 08:37:14 volumio volumio[6102]:   19:     0x558f806f2736 - ::poll::hdecdb1a04917de4b
Oct 26 08:37:14 volumio volumio[6102]:   20:     0x558f8070a149 - futures::task_impl::std::set::h6dd4b73f744a06d4
Oct 26 08:37:14 volumio volumio[6102]:   21:     0x558f807203bd - std::thread::local::LocalKey::with::hc9bf2e930c1fdc36
Oct 26 08:37:14 volumio volumio[6102]:   22:     0x558f80706c56 - tokio_current_thread::Entered
::block_on::h372d7b837fe25d26
Oct 26 08:37:14 volumio volumio[6102]:   23:     0x558f8071fb48 - std::thread::local::LocalKey::with::h247079172a6ad00b
Oct 26 08:37:14 volumio volumio[6102]:   24:     0x558f807098c5 - tokio::runtime::current_thread::runtime::Runtime::block_on::h538e89536cb974b3
Oct 26 08:37:14 volumio volumio[6102]:   25:     0x558f806f47e9 - vollibrespot::main::hf67292b633164777
Oct 26 08:37:14 volumio volumio[6102]:   26:     0x558f8070ec73 - std::sys_common::backtrace::__rust_begin_short_backtrace::h9396fe1ba8d4dd7f
Oct 26 08:37:14 volumio volumio[6102]:   27:     0x558f80731ce9 - std::rt::lang_start::{{closure}}::h0862538761d46dbc
Oct 26 08:37:14 volumio volumio[6102]:   28:     0x558f80bb87b1 - core::ops::function::impls:: for &F>::call_once::hb7014f43484a8b4e
Oct 26 08:37:14 volumio volumio[909]: [SpotifyConnect] Restarting Vollibrespot Daemon
Oct 26 08:37:14 volumio volumio[6102]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/ops/function.rs:259:13
Oct 26 08:37:14 volumio volumio[6102]:   29:     0x558f80bb87b1 - std::panicking::try::do_call::h7bc9dc436daeb8c7
Oct 26 08:37:14 volumio volumio[6102]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:492:40
Oct 26 08:37:14 volumio volumio[6102]:   30:     0x558f80bb87b1 - std::panicking::try::h653d68a27ff5f175
Oct 26 08:37:14 volumio volumio[6102]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:456:19
Oct 26 08:37:14 volumio volumio[6102]:   31:     0x558f80bb87b1 - std::panic::catch_unwind::h9d739f9f59895e68
Oct 26 08:37:14 volumio volumio[6102]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panic.rs:137:14
Oct 26 08:37:14 volumio volumio[6102]:   32:     0x558f80bb87b1 - std::rt::lang_start_internal::{{closure}}::hf006f2bc7ce22bbe
Oct 26 08:37:14 volumio volumio[6102]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/rt.rs:128:48
Oct 26 08:37:14 volumio volumio[6102]:   33:     0x558f80bb87b1 - std::panicking::try::do_call::hfb39d6df61a2e69f
Oct 26 08:37:14 volumio volumio[6102]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:492:40
Oct 26 08:37:14 volumio volumio[6102]:   34:     0x558f80bb87b1 - std::panicking::try::h13e2d225134958ac
Oct 26 08:37:14 volumio volumio[6102]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:456:19
Oct 26 08:37:14 volumio volumio[6102]:   35:     0x558f80bb87b1 - std::panic::catch_unwind::h3bd49b5a5dfb1a50
Oct 26 08:37:14 volumio volumio[6102]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panic.rs:137:14
Oct 26 08:37:14 volumio volumio[6102]:   36:     0x558f80bb87b1 - std::rt::lang_start_internal::h2ba92edce36c035e
Oct 26 08:37:14 volumio volumio[6102]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/rt.rs:128:20
Oct 26 08:37:14 volumio volumio[6102]:   37:     0x558f806f7562 - main
Oct 26 08:37:14 volumio volumio[6102]:   38:     0x7f43004ca09b - __libc_start_main
Oct 26 08:37:14 volumio volumio[6102]:   39:     0x558f806c578a - _start
Oct 26 08:37:14 volumio volumio[6102]:   40:                0x0 - 
Oct 26 08:37:14 volumio systemd[1]: volspotconnect.service: Main process exited, code=exited, status=101/n/a
Oct 26 08:37:14 volumio systemd[1]: volspotconnect.service: Failed with result 'exit-code'.
Oct 26 08:37:14 volumio volumio[909]: info: FusionDsp - {"Reload":{"result":"Ok"}}
Oct 26 08:37:14 volumio volumio[909]: error: updateQueue error: null
Oct 26 08:37:14 volumio sudo[6157]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service
Oct 26 08:37:14 volumio volumio[909]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf
Oct 26 08:37:14 volumio volumio[909]: info: Reading ALSA contributions from plugins.
Oct 26 08:37:14 volumio sudo[6157]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 26 08:37:14 volumio volumio[909]: info: MPD Permissions set
Oct 26 08:37:14 volumio volumio[909]: info: Shairport-Sync Started
Oct 26 08:37:14 volumio systemd[1]: Stopped Volspotconnect2 Daemon.
Oct 26 08:37:14 volumio sudo[6155]:     root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Oct 26 08:37:14 volumio sudo[6155]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 26 08:37:14 volumio sudo[6155]: pam_unix(sudo:session): session closed for user root
Oct 26 08:37:14 volumio systemd[1]: Started Volspotconnect2 Daemon.
Oct 26 08:37:14 volumio volumio[6160]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31
Oct 26 08:37:14 volumio volumio[6160]: Reading Config from "/tmp/volspotify.toml"
Oct 26 08:37:14 volumio volumio[6160]: [Vollibrespot] : Using Alsa backend with device: volumio
Oct 26 08:37:14 volumio sudo[6157]: pam_unix(sudo:session): session closed for user root
Oct 26 08:37:14 volumio volumio[909]: [SpotifyConnect] Vollibrespot Daemon service restarted!
Oct 26 08:37:14 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:14 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:14 volumio volumio[6160]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" }
Oct 26 08:37:14 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 26 08:37:14 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber
Oct 26 08:37:14 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:14 volumio volumio[909]: info: Starting Shairport Sync
Oct 26 08:37:14 volumio volumio[6160]: [Vollibrespot] : Connecting to AP "ap-gae2.spotify.com:443"
Oct 26 08:37:14 volumio sudo[6189]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Oct 26 08:37:14 volumio sudo[6189]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 26 08:37:14 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Oct 26 08:37:14 volumio volumio[909]: info: Asound.conf file unchanged, so no further update is needed
Oct 26 08:37:14 volumio volumio[909]: info: Output device has changed, restarting MPD
Oct 26 08:37:14 volumio systemd[1]: shairport-sync.service: Succeeded.
Oct 26 08:37:14 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Oct 26 08:37:14 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Oct 26 08:37:14 volumio sudo[6189]: pam_unix(sudo:session): session closed for user root
Oct 26 08:37:14 volumio sudo[6201]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Oct 26 08:37:14 volumio sudo[6201]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 26 08:37:15 volumio sudo[6201]: pam_unix(sudo:session): session closed for user root
Oct 26 08:37:15 volumio volumio[909]: info: Output device has changed, restarting Shairport Sync
Oct 26 08:37:15 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:15 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 26 08:37:15 volumio sudo[6205]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Oct 26 08:37:15 volumio sudo[6205]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 26 08:37:15 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM
Oct 26 08:37:15 volumio systemd[1]: mpd.service: Succeeded.
Oct 26 08:37:15 volumio systemd[1]: Stopped Music Player Daemon.
Oct 26 08:37:15 volumio volumio[909]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Oct 26 08:37:15 volumio volumio[909]: [SpotifyConnect] Creating VLS config file
Oct 26 08:37:15 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:15 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:15 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:15 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:15 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:15 volumio volumio[909]: info: Preparing to generate the ALSA configuration file
Oct 26 08:37:15 volumio systemd[1]: Starting Music Player Daemon...
Oct 26 08:37:15 volumio volumio[909]: info: PLUGIN START: fusiondsp
Oct 26 08:37:15 volumio volumio[909]: info: Loading i18n strings for locale vi
Oct 26 08:37:15 volumio volumio[909]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found.
Oct 26 08:37:15 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile
Oct 26 08:37:15 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:15 volumio volumio[909]: info: FusionDsp -  mixtype--------------------- Hardware
Oct 26 08:37:15 volumio volumio[909]: info: Done.
Oct 26 08:37:15 volumio volumio[909]: [SpotifyConnect] Restarting Vollibrespot Daemon
Oct 26 08:37:15 volumio volumio[6160]: thread 'main' panicked at 'Authentication failed with reason: BadCredentials', /build/cache/git/checkouts/librespot-6f197fd632ef9380/08d8bcc/core/src/connection/mod.rs:93:21
Oct 26 08:37:15 volumio volumio[6160]: stack backtrace:
Oct 26 08:37:15 volumio sudo[6225]:     root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Oct 26 08:37:15 volumio sudo[6225]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 26 08:37:15 volumio volumio[6160]:    0:     0x5619860700cd - std::backtrace_rs::backtrace::libunwind::trace::hee598835bc88d35b
Oct 26 08:37:15 volumio volumio[6160]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/../../backtrace/src/backtrace/libunwind.rs:93:5
Oct 26 08:37:15 volumio volumio[6160]:    1:     0x5619860700cd - std::backtrace_rs::backtrace::trace_unsynchronized::h9cdc730ba5cf5d72
Oct 26 08:37:15 volumio volumio[6160]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5
Oct 26 08:37:15 volumio volumio[6160]:    2:     0x5619860700cd - std::sys_common::backtrace::_print_fmt::h75aeaf7ed30e43fa
Oct 26 08:37:15 volumio volumio[6160]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:66:5
Oct 26 08:37:15 volumio volumio[6160]:    3:     0x5619860700cd - ::fmt::h606862f787600875
Oct 26 08:37:15 volumio volumio[6160]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:45:22
Oct 26 08:37:15 volumio sudo[6225]: pam_unix(sudo:session): session closed for user root
Oct 26 08:37:15 volumio volumio[6160]:    4:     0x561986095f5c - core::fmt::write::he803f0f418caf762
Oct 26 08:37:15 volumio volumio[6160]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/fmt/mod.rs:1190:17
Oct 26 08:37:15 volumio volumio[6160]:    5:     0x561986068cb8 - std::io::Write::write_fmt::h70bc45872f37e7bb
Oct 26 08:37:15 volumio volumio[6160]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/io/mod.rs:1657:15
Oct 26 08:37:15 volumio volumio[6160]:    6:     0x5619860725a7 - std::sys_common::backtrace::_print::h64d038cf8ac3e13e
Oct 26 08:37:15 volumio volumio[6160]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:48:5
Oct 26 08:37:15 volumio volumio[6160]:    7:     0x5619860725a7 - std::sys_common::backtrace::print::h359300b4a7fccf65
Oct 26 08:37:15 volumio volumio[6160]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:35:9
Oct 26 08:37:15 volumio volumio[6160]:    8:     0x5619860725a7 - std::panicking::default_hook::{{closure}}::hf51be35e2f510149
Oct 26 08:37:15 volumio volumio[6160]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:295:22
Oct 26 08:37:15 volumio volumio[6160]:    9:     0x561986072270 - std::panicking::default_hook::h03ca0f22e1d2d25e
Oct 26 08:37:15 volumio volumio[6160]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:314:9
Oct 26 08:37:15 volumio volumio[6160]:   10:     0x561986072cf9 - std::panicking::rust_panic_with_hook::h3b7380e99b825b63
Oct 26 08:37:15 volumio volumio[6160]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:698:17
Oct 26 08:37:15 volumio volumio[6160]:   11:     0x5619860729e7 - std::panicking::begin_panic_handler::{{closure}}::h8e849d0710154ce0
Oct 26 08:37:15 volumio volumio[6160]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:588:13
Oct 26 08:37:15 volumio volumio[6160]:   12:     0x561986070594 - std::sys_common::backtrace::__rust_end_short_backtrace::hedcdaddbd4c46cc5
Oct 26 08:37:15 volumio volumio[6160]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:138:18
Oct 26 08:37:15 volumio volumio[6160]:   13:     0x5619860726f9 - rust_begin_unwind
Oct 26 08:37:15 volumio volumio[6160]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:584:5
Oct 26 08:37:15 volumio volumio[6160]:   14:     0x561985b7bf53 - core::panicking::panic_fmt::he1bbc7336d49a357
Oct 26 08:37:15 volumio volumio[6160]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/panicking.rs:143:14
Oct 26 08:37:15 volumio volumio[6160]:   15:     0x561985d4bf56 - futures::future::chain::Chain::poll::hf0e729e3d4d8df0d
Oct 26 08:37:15 volumio volumio[6160]:   16:     0x561985dbea19 -  as futures::future::Future>::poll::hb2bebc352335cc1a
Oct 26 08:37:15 volumio volumio[6160]:   17:     0x561985d4a8e6 - futures::future::chain::Chain::poll::hda5263cfb178296b
Oct 26 08:37:15 volumio volumio[6160]:   18:     0x561985dbffe1 -  as futures::future::Future>::poll::h7445370ea1c2ee99
Oct 26 08:37:15 volumio volumio[6160]:   19:     0x561985ba9736 - ::poll::hdecdb1a04917de4b
Oct 26 08:37:15 volumio volumio[6160]:   20:     0x561985bc1149 - futures::task_impl::std::set::h6dd4b73f744a06d4
Oct 26 08:37:15 volumio volumio[6160]:   21:     0x561985bd73bd - std::thread::local::LocalKey::with::hc9bf2e930c1fdc36
Oct 26 08:37:15 volumio volumio[6160]:   22:     0x561985bbdc56 - tokio_current_thread::Entered
::block_on::h372d7b837fe25d26
Oct 26 08:37:15 volumio volumio[6160]:   23:     0x561985bd6b48 - std::thread::local::LocalKey::with::h247079172a6ad00b
Oct 26 08:37:15 volumio volumio[6160]:   24:     0x561985bc08c5 - tokio::runtime::current_thread::runtime::Runtime::block_on::h538e89536cb974b3
Oct 26 08:37:15 volumio volumio[6160]:   25:     0x561985bab7e9 - vollibrespot::main::hf67292b633164777
Oct 26 08:37:15 volumio volumio[6160]:   26:     0x561985bc5c73 - std::sys_common::backtrace::__rust_begin_short_backtrace::h9396fe1ba8d4dd7f
Oct 26 08:37:15 volumio volumio[6160]:   27:     0x561985be8ce9 - std::rt::lang_start::{{closure}}::h0862538761d46dbc
Oct 26 08:37:15 volumio volumio[6160]:   28:     0x56198606f7b1 - core::ops::function::impls:: for &F>::call_once::hb7014f43484a8b4e
Oct 26 08:37:15 volumio sudo[6227]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service
Oct 26 08:37:15 volumio sudo[6227]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 26 08:37:15 volumio volumio[909]: info: Checking Spotify Web API
Oct 26 08:37:15 volumio volumio[6160]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/ops/function.rs:259:13
Oct 26 08:37:15 volumio volumio[6160]:   29:     0x56198606f7b1 - std::panicking::try::do_call::h7bc9dc436daeb8c7
Oct 26 08:37:15 volumio volumio[6160]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:492:40
Oct 26 08:37:15 volumio volumio[6160]:   30:     0x56198606f7b1 - std::panicking::try::h653d68a27ff5f175
Oct 26 08:37:15 volumio volumio[6160]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:456:19
Oct 26 08:37:15 volumio volumio[6160]:   31:     0x56198606f7b1 - std::panic::catch_unwind::h9d739f9f59895e68
Oct 26 08:37:15 volumio volumio[6160]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panic.rs:137:14
Oct 26 08:37:15 volumio volumio[6160]:   32:     0x56198606f7b1 - std::rt::lang_start_internal::{{closure}}::hf006f2bc7ce22bbe
Oct 26 08:37:15 volumio volumio[6160]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/rt.rs:128:48
Oct 26 08:37:15 volumio volumio[6160]:   33:     0x56198606f7b1 - std::panicking::try::do_call::hfb39d6df61a2e69f
Oct 26 08:37:15 volumio volumio[6160]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:492:40
Oct 26 08:37:15 volumio volumio[6160]:   34:     0x56198606f7b1 - std::panicking::try::h13e2d225134958ac
Oct 26 08:37:15 volumio volumio[6160]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:456:19
Oct 26 08:37:15 volumio volumio[6160]:   35:     0x56198606f7b1 - std::panic::catch_unwind::h3bd49b5a5dfb1a50
Oct 26 08:37:15 volumio volumio[6160]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panic.rs:137:14
Oct 26 08:37:15 volumio volumio[6160]:   36:     0x56198606f7b1 - std::rt::lang_start_internal::h2ba92edce36c035e
Oct 26 08:37:15 volumio volumio[6160]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/rt.rs:128:20
Oct 26 08:37:15 volumio volumio[6160]:   37:     0x561985bae562 - main
Oct 26 08:37:15 volumio volumio[6160]:   38:     0x7f9aeb3b509b - __libc_start_main
Oct 26 08:37:15 volumio volumio[6160]:   39:     0x561985b7c78a - _start
Oct 26 08:37:15 volumio volumio[6160]:   40:                0x0 - 
Oct 26 08:37:15 volumio systemd[1]: volspotconnect.service: Main process exited, code=exited, status=101/n/a
Oct 26 08:37:15 volumio systemd[1]: volspotconnect.service: Failed with result 'exit-code'.
Oct 26 08:37:15 volumio volumio[909]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf
Oct 26 08:37:15 volumio volumio[909]: info: Reading ALSA contributions from plugins.
Oct 26 08:37:15 volumio systemd[1]: Stopped Volspotconnect2 Daemon.
Oct 26 08:37:15 volumio systemd[1]: volspotconnect.service: Start request repeated too quickly.
Oct 26 08:37:15 volumio systemd[1]: volspotconnect.service: Failed with result 'exit-code'.
Oct 26 08:37:15 volumio systemd[1]: Failed to start Volspotconnect2 Daemon.
Oct 26 08:37:15 volumio sudo[6227]: pam_unix(sudo:session): session closed for user root
Oct 26 08:37:15 volumio volumio[909]: info: CoreCommandRouter::getUIConfigOnPlugin
Oct 26 08:37:15 volumio volumio[909]: info: MPD Permissions set
Oct 26 08:37:15 volumio volumio[909]: info: Shairport-Sync Started
Oct 26 08:37:15 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:15 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:15 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:15 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:15 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:15 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 26 08:37:15 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber
Oct 26 08:37:15 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:15 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Oct 26 08:37:15 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Oct 26 08:37:15 volumio volumio[909]: info: Discovery: Getting this device information
Oct 26 08:37:15 volumio volumio[909]: info: CoreCommandRouter::volumioGetState
Oct 26 08:37:15 volumio volumio[909]: info: CorePlayQueue::getTrack 0
Oct 26 08:37:15 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Oct 26 08:37:15 volumio volumio[909]: info: FusionDsp -
Oct 26 08:37:15 volumio volumio[909]: info: FusionDsp -
Oct 26 08:37:16 volumio volumio[909]: info: FusionDsp - undefined
Oct 26 08:37:16 volumio volumio[909]: info: FusionDsp - undefined
Oct 26 08:37:16 volumio volumio[909]: info: Starting Shairport Sync
Oct 26 08:37:16 volumio sudo[6253]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Oct 26 08:37:16 volumio sudo[6253]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 26 08:37:16 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Oct 26 08:37:16 volumio systemd[1]: shairport-sync.service: Succeeded.
Oct 26 08:37:16 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Oct 26 08:37:16 volumio volumio[909]: info: Asound.conf file unchanged, so no further update is needed
Oct 26 08:37:16 volumio volumio[909]: info: Output device has changed, restarting MPD
Oct 26 08:37:16 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Oct 26 08:37:16 volumio sudo[6253]: pam_unix(sudo:session): session closed for user root
Oct 26 08:37:16 volumio sudo[6266]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Oct 26 08:37:16 volumio sudo[6266]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 26 08:37:16 volumio sudo[6266]: pam_unix(sudo:session): session closed for user root
Oct 26 08:37:16 volumio volumio[909]: info: Output device has changed, restarting Shairport Sync
Oct 26 08:37:16 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:16 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 26 08:37:16 volumio sudo[6269]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Oct 26 08:37:16 volumio sudo[6269]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 26 08:37:16 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM
Oct 26 08:37:16 volumio systemd[1]: mpd.service: Succeeded.
Oct 26 08:37:16 volumio systemd[1]: Stopped Music Player Daemon.
Oct 26 08:37:16 volumio volumio[909]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Oct 26 08:37:16 volumio volumio[909]: [SpotifyConnect] Creating VLS config file
Oct 26 08:37:16 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:16 volumio volumio[909]: info: Preparing to generate the ALSA configuration file
Oct 26 08:37:16 volumio systemd[1]: Starting Music Player Daemon...
Oct 26 08:37:16 volumio volumio[909]: info: PLUGIN START: fusiondsp
Oct 26 08:37:16 volumio volumio[909]: info: Loading i18n strings for locale vi
Oct 26 08:37:16 volumio volumio[909]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found.
Oct 26 08:37:16 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile
Oct 26 08:37:16 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:16 volumio volumio[909]: info: FusionDsp -  mixtype--------------------- Hardware
Oct 26 08:37:16 volumio volumio[909]: info: Done.
Oct 26 08:37:16 volumio volumio[909]: [SpotifyConnect] Restarting Vollibrespot Daemon
Oct 26 08:37:16 volumio volumio[909]: info: Checking Spotify Web API
Oct 26 08:37:16 volumio volumio[909]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf
Oct 26 08:37:16 volumio volumio[909]: info: Reading ALSA contributions from plugins.
Oct 26 08:37:16 volumio volumio[909]: info: CoreCommandRouter::getUIConfigOnPlugin
Oct 26 08:37:16 volumio volumio[909]: info: CoreCommandRouter::getUIConfigOnPlugin
Oct 26 08:37:16 volumio volumio[909]: info: MPD Permissions set
Oct 26 08:37:16 volumio volumio[909]: info: Shairport-Sync Started
Oct 26 08:37:16 volumio sudo[6276]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service
Oct 26 08:37:16 volumio sudo[6276]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 26 08:37:16 volumio sudo[6274]:     root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Oct 26 08:37:16 volumio sudo[6274]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 26 08:37:16 volumio sudo[6274]: pam_unix(sudo:session): session closed for user root
Oct 26 08:37:16 volumio systemd[1]: volspotconnect.service: Start request repeated too quickly.
Oct 26 08:37:16 volumio systemd[1]: volspotconnect.service: Failed with result 'exit-code'.
Oct 26 08:37:16 volumio systemd[1]: Failed to start Volspotconnect2 Daemon.
Oct 26 08:37:16 volumio sudo[6276]: pam_unix(sudo:session): session closed for user root
Oct 26 08:37:16 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:16 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:16 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 26 08:37:16 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber
Oct 26 08:37:17 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:17 volumio volumio[909]: info: FusionDsp -
Oct 26 08:37:17 volumio volumio[909]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists
Oct 26 08:37:17 volumio volumio[909]: error: FusionDsp -  ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo
Oct 26 08:37:17 volumio volumio[909]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists
Oct 26 08:37:17 volumio volumio[909]: info: camilladsp service started and running in background, instance 3
Oct 26 08:37:17 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:17 volumio volumio[909]: cannot open device 'volumioHw': Device or resource busy
Oct 26 08:37:17 volumio volumio[909]: error: FusionDsp -  ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json
Oct 26 08:37:17 volumio volumio[909]: cannot open device 'volumioHw': Device or resource busy
Oct 26 08:37:17 volumio volumio[909]: info: FusionDsp loaded
Oct 26 08:37:17 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Oct 26 08:37:17 volumio volumio[909]: info: FusionDsp -  Reporting Fusion DSP Enabled
Oct 26 08:37:17 volumio volumio[909]: info: Adding Signal Path Element [object Object]
Oct 26 08:37:17 volumio volumio[909]: info: Updating fusiondspeq DSP Signal Path Element
Oct 26 08:37:17 volumio volumio[909]: info: FusionDsp -  ---- installed callbackRead
Oct 26 08:37:17 volumio volumio[909]: info: FusionDsp -  If filter freq >samplerate/2 then disable it
Oct 26 08:37:17 volumio sudo[6316]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service
Oct 26 08:37:17 volumio volumio[909]: info: FusionDsp -
Oct 26 08:37:17 volumio sudo[6316]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 26 08:37:17 volumio sudo[6316]: pam_unix(sudo:session): session closed for user root
Oct 26 08:37:18 volumio volumio[909]: info: FusionDsp - undefined
Oct 26 08:37:18 volumio mpd[6279]: Oct 26 08:37 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Oct 26 08:37:18 volumio volumio[909]: info: FusionDsp -  ---- read samplerate, raw:
Oct 26 08:37:18 volumio volumio[909]: error: FusionDsp - invalid sample rate
Oct 26 08:37:18 volumio volumio[909]: info: FusionDsp -  ---- read samplerate, raw:
Oct 26 08:37:18 volumio volumio[909]: error: FusionDsp - invalid sample rate
Oct 26 08:37:18 volumio volumio[909]: info: FusionDsp -  ---- read samplerate, raw:
Oct 26 08:37:18 volumio volumio[909]: error: FusionDsp - invalid sample rate
Oct 26 08:37:18 volumio volumio[909]: info: Checking Spotify Web API
Oct 26 08:37:18 volumio volumio[909]: info: FusionDsp - undefined
Oct 26 08:37:18 volumio systemd[1]: Started Music Player Daemon.
Oct 26 08:37:18 volumio sudo[6269]: pam_unix(sudo:session): session closed for user root
Oct 26 08:37:18 volumio sudo[6150]: pam_unix(sudo:session): session closed for user root
Oct 26 08:37:18 volumio sudo[6205]: pam_unix(sudo:session): session closed for user root
Oct 26 08:37:18 volumio volumio[909]: info: Starting Shairport Sync
Oct 26 08:37:18 volumio sudo[6322]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Oct 26 08:37:18 volumio sudo[6322]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 26 08:37:18 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Oct 26 08:37:18 volumio systemd[1]: shairport-sync.service: Succeeded.
Oct 26 08:37:18 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Oct 26 08:37:18 volumio systemd[1]: shairport-sync.service: Start request repeated too quickly.
Oct 26 08:37:18 volumio systemd[1]: shairport-sync.service: Failed with result 'start-limit-hit'.
Oct 26 08:37:18 volumio systemd[1]: Failed to start Shairport Sync - AirPlay Audio Receiver.
Oct 26 08:37:18 volumio sudo[6322]: pam_unix(sudo:session): session closed for user root
Oct 26 08:37:18 volumio volumio[909]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11
Oct 26 08:37:18 volumio volumio[909]: info: Shairport-sync error: Error: Command failed: /usr/bin/sudo /bin/systemctl restart shairport-sync
Oct 26 08:37:18 volumio volumio[909]: Job for shairport-sync.service failed.
Oct 26 08:37:18 volumio volumio[909]: See "systemctl status shairport-sync.service" and "journalctl -xe" for details.
Oct 26 08:37:18 volumio volumio[909]: info: Asound.conf file unchanged, so no further update is needed
Oct 26 08:37:18 volumio volumio[909]: info: Output device has changed, restarting MPD
Oct 26 08:37:18 volumio sudo[6346]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Oct 26 08:37:18 volumio sudo[6346]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 26 08:37:18 volumio volumio[909]: info: Output device has changed, restarting Shairport Sync
Oct 26 08:37:18 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:18 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 26 08:37:18 volumio sudo[6346]: pam_unix(sudo:session): session closed for user root
Oct 26 08:37:18 volumio sudo[6349]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Oct 26 08:37:18 volumio sudo[6349]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 26 08:37:18 volumio systemd[1]: Stopping Music Player Daemon...
Oct 26 08:37:18 volumio systemd[1]: mpd.service: Succeeded.
Oct 26 08:37:18 volumio systemd[1]: Stopped Music Player Daemon.
Oct 26 08:37:18 volumio volumio[909]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Oct 26 08:37:18 volumio volumio[909]: [SpotifyConnect] Creating VLS config file
Oct 26 08:37:18 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:18 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:18 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:18 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:18 volumio volumio[909]: info: Preparing to generate the ALSA configuration file
Oct 26 08:37:18 volumio systemd[1]: Starting Music Player Daemon...
Oct 26 08:37:18 volumio volumio[909]: [SpotifyConnect] Restarting Vollibrespot Daemon
Oct 26 08:37:18 volumio sudo[6356]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service
Oct 26 08:37:18 volumio sudo[6356]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 26 08:37:18 volumio sudo[6354]:     root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Oct 26 08:37:18 volumio sudo[6354]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 26 08:37:18 volumio sudo[6354]: pam_unix(sudo:session): session closed for user root
Oct 26 08:37:18 volumio systemd[1]: volspotconnect.service: Start request repeated too quickly.
Oct 26 08:37:18 volumio systemd[1]: volspotconnect.service: Failed with result 'exit-code'.
Oct 26 08:37:18 volumio systemd[1]: Failed to start Volspotconnect2 Daemon.
Oct 26 08:37:18 volumio sudo[6356]: pam_unix(sudo:session): session closed for user root
Oct 26 08:37:18 volumio volumio[909]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists
Oct 26 08:37:18 volumio volumio[909]: error: FusionDsp -  ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo
Oct 26 08:37:18 volumio volumio[909]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists
Oct 26 08:37:18 volumio volumio[909]: info: camilladsp service started and running in background, instance 4
Oct 26 08:37:18 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:19 volumio volumio[909]: cannot open device 'volumioHw': Device or resource busy
Oct 26 08:37:19 volumio volumio[909]: error: FusionDsp -  ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json
Oct 26 08:37:19 volumio volumio[909]: cannot open device 'volumioHw': Device or resource busy
Oct 26 08:37:19 volumio volumio[909]: info: FusionDsp loaded
Oct 26 08:37:19 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Oct 26 08:37:19 volumio volumio[909]: info: FusionDsp -  Reporting Fusion DSP Enabled
Oct 26 08:37:19 volumio volumio[909]: info: Adding Signal Path Element [object Object]
Oct 26 08:37:19 volumio volumio[909]: info: Updating fusiondspeq DSP Signal Path Element
Oct 26 08:37:19 volumio volumio[909]: info: FusionDsp -  ---- installed callbackRead
Oct 26 08:37:19 volumio volumio[909]: info: FusionDsp -  If filter freq >samplerate/2 then disable it
Oct 26 08:37:19 volumio volumio[909]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf
Oct 26 08:37:19 volumio sudo[6376]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service
Oct 26 08:37:19 volumio volumio[909]: info: Reading ALSA contributions from plugins.
Oct 26 08:37:19 volumio sudo[6376]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 26 08:37:19 volumio volumio[909]: info: FusionDsp - {"Reload":{"result":"Ok"}}
Oct 26 08:37:19 volumio volumio[909]: error: MPD error: Error: write EPIPE
Oct 26 08:37:19 volumio volumio[909]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"}
Oct 26 08:37:19 volumio volumio[909]: error: MPD error: The expression evaluated to a falsy value:
Oct 26 08:37:19 volumio volumio[909]:   assert.ok(self.idling)
Oct 26 08:37:19 volumio sudo[6376]: pam_unix(sudo:session): session closed for user root
Oct 26 08:37:19 volumio volumio[909]: error: The expression evaluated to a falsy value:
Oct 26 08:37:19 volumio volumio[909]:   assert.ok(self.idling)
Oct 26 08:37:19 volumio volumio[909]: error: MPD error: Error: write EPIPE
Oct 26 08:37:19 volumio volumio[909]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"}
Oct 26 08:37:19 volumio volumio[909]: error: MPD error: The expression evaluated to a falsy value:
Oct 26 08:37:19 volumio volumio[909]:   assert.ok(self.idling)
Oct 26 08:37:19 volumio volumio[909]: error: The expression evaluated to a falsy value:
Oct 26 08:37:19 volumio volumio[909]:   assert.ok(self.idling)
Oct 26 08:37:19 volumio volumio[909]: error: MPD error: Error: write EPIPE
Oct 26 08:37:19 volumio volumio[909]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"}
Oct 26 08:37:19 volumio volumio[909]: info: FusionDsp -  ---- read samplerate, raw:
Oct 26 08:37:19 volumio volumio[909]: error: FusionDsp - invalid sample rate
Oct 26 08:37:19 volumio volumio[909]: info: FusionDsp -  ---- read samplerate, raw:
Oct 26 08:37:19 volumio volumio[909]: error: FusionDsp - invalid sample rate
Oct 26 08:37:19 volumio volumio[909]: info: FusionDsp -  ---- read samplerate, raw:
Oct 26 08:37:19 volumio volumio[909]: error: FusionDsp - invalid sample rate
Oct 26 08:37:19 volumio volumio[909]: info: FusionDsp -  ---- read samplerate, raw:
Oct 26 08:37:19 volumio volumio[909]: error: FusionDsp - invalid sample rate
Oct 26 08:37:19 volumio volumio[909]: info: MPD Permissions set
Oct 26 08:37:19 volumio volumio[909]: info: CoreCommandRouter::volumioGetState
Oct 26 08:37:19 volumio volumio[909]: info: CorePlayQueue::getTrack 0
Oct 26 08:37:19 volumio volumio[909]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12
Oct 26 08:37:19 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:19 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 26 08:37:19 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber
Oct 26 08:37:19 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:19 volumio volumio[909]: info: FusionDsp - {"Reload":{"result":"Ok"}}
Oct 26 08:37:20 volumio volumio[909]: info: Starting Shairport Sync
Oct 26 08:37:20 volumio sudo[6449]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Oct 26 08:37:20 volumio sudo[6449]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 26 08:37:20 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Oct 26 08:37:20 volumio sudo[6449]: pam_unix(sudo:session): session closed for user root
Oct 26 08:37:20 volumio mpd[6359]: Oct 26 08:37 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Oct 26 08:37:20 volumio volumio[909]: info: Asound.conf file unchanged, so no further update is needed
Oct 26 08:37:20 volumio volumio[909]: info: Output device has changed, restarting MPD
Oct 26 08:37:20 volumio systemd[1]: Started Music Player Daemon.
Oct 26 08:37:20 volumio sudo[6349]: pam_unix(sudo:session): session closed for user root
Oct 26 08:37:20 volumio sudo[6469]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Oct 26 08:37:20 volumio sudo[6469]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 26 08:37:20 volumio sudo[6469]: pam_unix(sudo:session): session closed for user root
Oct 26 08:37:20 volumio volumio[909]: info: Output device has changed, restarting Shairport Sync
Oct 26 08:37:20 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:20 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 26 08:37:20 volumio sudo[6474]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Oct 26 08:37:20 volumio sudo[6474]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 26 08:37:20 volumio systemd[1]: Stopping Music Player Daemon...
Oct 26 08:37:20 volumio systemd[1]: mpd.service: Succeeded.
Oct 26 08:37:20 volumio systemd[1]: Stopped Music Player Daemon.
Oct 26 08:37:20 volumio volumio[909]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Oct 26 08:37:20 volumio volumio[909]: [SpotifyConnect] Creating VLS config file
Oct 26 08:37:20 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:20 volumio systemd[1]: Starting Music Player Daemon...
Oct 26 08:37:20 volumio volumio[909]: [SpotifyConnect] Restarting Vollibrespot Daemon
Oct 26 08:37:20 volumio volumio[909]: info: Shairport-Sync Started
Oct 26 08:37:20 volumio sudo[6481]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service
Oct 26 08:37:20 volumio sudo[6481]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 26 08:37:20 volumio sudo[6479]:     root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Oct 26 08:37:20 volumio sudo[6479]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 26 08:37:20 volumio sudo[6479]: pam_unix(sudo:session): session closed for user root
Oct 26 08:37:20 volumio systemd[1]: Started Volspotconnect2 Daemon.
Oct 26 08:37:20 volumio sudo[6481]: pam_unix(sudo:session): session closed for user root
Oct 26 08:37:20 volumio volumio[909]: info: Checking Spotify Web API
Oct 26 08:37:20 volumio volumio[6493]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31
Oct 26 08:37:20 volumio volumio[6493]: Reading Config from "/tmp/volspotify.toml"
Oct 26 08:37:20 volumio volumio[6493]: [Vollibrespot] : Using Alsa backend with device: volumio
Oct 26 08:37:20 volumio volumio[909]: [SpotifyConnect] Vollibrespot Daemon service restarted!
Oct 26 08:37:20 volumio volumio[909]: info: MPD Permissions set
Oct 26 08:37:20 volumio volumio[6493]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" }
Oct 26 08:37:20 volumio volumio[6493]: [Vollibrespot] : Connecting to AP "ap-gae2.spotify.com:443"
Oct 26 08:37:21 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:21 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:21 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:21 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:21 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 26 08:37:21 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber
Oct 26 08:37:21 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 26 08:37:21 volumio volumio[6493]: thread 'main' panicked at 'Authentication failed with reason: BadCredentials', /build/cache/git/checkouts/librespot-6f197fd632ef9380/08d8bcc/core/src/connection/mod.rs:93:21
Oct 26 08:37:21 volumio volumio[6493]: stack backtrace:
Oct 26 08:37:21 volumio volumio[6493]:    0:     0x56386de270cd - std::backtrace_rs::backtrace::libunwind::trace::hee598835bc88d35b
Oct 26 08:37:21 volumio volumio[6493]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/../../backtrace/src/backtrace/libunwind.rs:93:5
Oct 26 08:37:21 volumio volumio[6493]:    1:     0x56386de270cd - std::backtrace_rs::backtrace::trace_unsynchronized::h9cdc730ba5cf5d72
Oct 26 08:37:21 volumio volumio[6493]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5
Oct 26 08:37:21 volumio volumio[6493]:    2:     0x56386de270cd - std::sys_common::backtrace::_print_fmt::h75aeaf7ed30e43fa
Oct 26 08:37:21 volumio volumio[6493]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:66:5
Oct 26 08:37:21 volumio volumio[6493]:    3:     0x56386de270cd - ::fmt::h606862f787600875
Oct 26 08:37:21 volumio volumio[6493]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:45:22
Oct 26 08:37:21 volumio volumio[6493]:    4:     0x56386de4cf5c - core::fmt::write::he803f0f418caf762
Oct 26 08:37:21 volumio volumio[6493]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/fmt/mod.rs:1190:17
Oct 26 08:37:21 volumio volumio[6493]:    5:     0x56386de1fcb8 - std::io::Write::write_fmt::h70bc45872f37e7bb
Oct 26 08:37:21 volumio volumio[6493]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/io/mod.rs:1657:15
Oct 26 08:37:21 volumio volumio[6493]:    6:     0x56386de295a7 - std::sys_common::backtrace::_print::h64d038cf8ac3e13e
Oct 26 08:37:21 volumio volumio[6493]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:48:5
Oct 26 08:37:21 volumio volumio[6493]:    7:     0x56386de295a7 - std::sys_common::backtrace::print::h359300b4a7fccf65
Oct 26 08:37:21 volumio volumio[6493]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:35:9
Oct 26 08:37:21 volumio volumio[6493]:    8:     0x56386de295a7 - std::panicking::default_hook::{{closure}}::hf51be35e2f510149
Oct 26 08:37:21 volumio volumio[6493]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:295:22
Oct 26 08:37:21 volumio volumio[6493]:    9:     0x56386de29270 - std::panicking::default_hook::h03ca0f22e1d2d25e
Oct 26 08:37:21 volumio volumio[6493]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:314:9
Oct 26 08:37:21 volumio volumio[6493]:   10:     0x56386de29cf9 - std::panicking::rust_panic_with_hook::h3b7380e99b825b63
Oct 26 08:37:21 volumio volumio[6493]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:698:17
Oct 26 08:37:21 volumio volumio[6493]:   11:     0x56386de299e7 - std::panicking::begin_panic_handler::{{closure}}::h8e849d0710154ce0
Oct 26 08:37:21 volumio volumio[6493]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:588:13
Oct 26 08:37:21 volumio volumio[6493]:   12:     0x56386de27594 - std::sys_common::backtrace::__rust_end_short_backtrace::hedcdaddbd4c46cc5
Oct 26 08:37:21 volumio volumio[6493]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:138:18
Oct 26 08:37:21 volumio volumio[6493]:   13:     0x56386de296f9 - rust_begin_unwind
Oct 26 08:37:21 volumio volumio[6493]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:584:5
Oct 26 08:37:21 volumio volumio[6493]:   14:     0x56386d932f53 - core::panicking::panic_fmt::he1bbc7336d49a357
Oct 26 08:37:21 volumio volumio[6493]:                                at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/panicking.rs:143:14
Oct 26 08:37:21 volumio volumio[6493]:   15:     0x56386db02f56 - futures::future::chain::Chain::poll::hf0e729e3d4d8df0d
Oct 26 08:37:21 volumio volumio[6493]:   16:     0x56386db75a19 -  as futures::future::Future>::poll::hb2bebc352335cc1a
Oct 26 08:37:21 volumio volumio[6493]:   17:     0x56386db018e6 - futures::future::chain::Chain::poll::hda5263cfb178296b
Oct 26 08:37:21 volumio volumio[6493]:   18:     0x56386db76fe1 -  as futures::future::Future>::poll::h7445370ea1c2ee99
Oct 26 08:37:21 volumio volumio[6493]:   19:     0x56386d960736 - ::poll::hdecdb1a04917de4b
Oct 26 08:37:21 volumio volumio[6493]:   20:     0x56386d978149 - futures::task_impl::std::set::h6dd4b73f744a06d4
Oct 26 08:37:21 volumio volumio[6493]:   21:     0x56386d98e3bd - std::thread::local::LocalKey::with::hc9bf2e930c1fdc36
Oct 26 08:37:21 volumio volumio[6493]:   22:     0x56386d974c56 - tokio_current_thread::Entered
::block_on::h372d7b837fe25d26 Oct 26 08:37:21 volumio volumio[6493]: 23: 0x56386d98db48 - std::thread::local::LocalKey::with::h247079172a6ad00b Oct 26 08:37:21 volumio volumio[6493]: 24: 0x56386d9778c5 - tokio::runtime::current_thread::runtime::Runtime::block_on::h538e89536cb974b3 Oct 26 08:37:21 volumio volumio[6493]: 25: 0x56386d9627e9 - vollibrespot::main::hf67292b633164777 Oct 26 08:37:21 volumio volumio[6493]: 26: 0x56386d97cc73 - std::sys_common::backtrace::__rust_begin_short_backtrace::h9396fe1ba8d4dd7f Oct 26 08:37:21 volumio volumio[6493]: 27: 0x56386d99fce9 - std::rt::lang_start::{{closure}}::h0862538761d46dbc Oct 26 08:37:21 volumio volumio[6493]: 28: 0x56386de267b1 - core::ops::function::impls:: for &F>::call_once::hb7014f43484a8b4e Oct 26 08:37:21 volumio volumio[6493]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/ops/function.rs:259:13 Oct 26 08:37:21 volumio volumio[6493]: 29: 0x56386de267b1 - std::panicking::try::do_call::h7bc9dc436daeb8c7 Oct 26 08:37:21 volumio volumio[6493]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:492:40 Oct 26 08:37:21 volumio volumio[6493]: 30: 0x56386de267b1 - std::panicking::try::h653d68a27ff5f175 Oct 26 08:37:21 volumio volumio[6493]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:456:19 Oct 26 08:37:21 volumio volumio[6493]: 31: 0x56386de267b1 - std::panic::catch_unwind::h9d739f9f59895e68 Oct 26 08:37:21 volumio volumio[6493]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panic.rs:137:14 Oct 26 08:37:21 volumio volumio[6493]: 32: 0x56386de267b1 - std::rt::lang_start_internal::{{closure}}::hf006f2bc7ce22bbe Oct 26 08:37:21 volumio volumio[6493]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/rt.rs:128:48 Oct 26 08:37:21 volumio volumio[6493]: 33: 0x56386de267b1 - std::panicking::try::do_call::hfb39d6df61a2e69f Oct 26 08:37:21 volumio volumio[6493]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:492:40 Oct 26 08:37:21 volumio volumio[6493]: 34: 0x56386de267b1 - std::panicking::try::h13e2d225134958ac Oct 26 08:37:21 volumio volumio[6493]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:456:19 Oct 26 08:37:21 volumio volumio[6493]: 35: 0x56386de267b1 - std::panic::catch_unwind::h3bd49b5a5dfb1a50 Oct 26 08:37:21 volumio volumio[6493]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panic.rs:137:14 Oct 26 08:37:21 volumio volumio[6493]: 36: 0x56386de267b1 - std::rt::lang_start_internal::h2ba92edce36c035e Oct 26 08:37:21 volumio volumio[6493]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/rt.rs:128:20 Oct 26 08:37:21 volumio volumio[6493]: 37: 0x56386d965562 - main Oct 26 08:37:21 volumio volumio[6493]: 38: 0x7f9cebd4509b - __libc_start_main Oct 26 08:37:21 volumio volumio[6493]: 39: 0x56386d93378a - _start Oct 26 08:37:21 volumio volumio[6493]: 40: 0x0 - Oct 26 08:37:21 volumio systemd[1]: volspotconnect.service: Main process exited, code=exited, status=101/n/a Oct 26 08:37:21 volumio systemd[1]: volspotconnect.service: Failed with result 'exit-code'. Oct 26 08:37:21 volumio volumio[909]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/amd64 Oct 26 08:37:21 volumio volumio[909]: info: FusionDsp - Stopping FusionDsp service Oct 26 08:37:21 volumio volumio[909]: info: camilladsp service terminated, instance 4 Oct 26 08:37:21 volumio sudo[6554]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop fusiondsp.service Oct 26 08:37:21 volumio sudo[6554]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 08:37:21 volumio volumio[6074]: Backend configuration: Oct 26 08:37:21 volumio volumio[6074]: active_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml Oct 26 08:37:21 volumio volumio[6074]: active_config_txt: null Oct 26 08:37:21 volumio volumio[6074]: camilla_host: 127.0.0.1 Oct 26 08:37:21 volumio volumio[6074]: camilla_port: 9876 Oct 26 08:37:21 volumio volumio[6074]: coeff_dir: /data/INTERNAL/FusionDsp/filters Oct 26 08:37:21 volumio volumio[6074]: config_dir: /data/configuration/audio_interface/fusiondsp Oct 26 08:37:21 volumio volumio[6074]: default_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml Oct 26 08:37:21 volumio volumio[6074]: log_file: /tmp/camilladsp.log Oct 26 08:37:21 volumio volumio[6074]: on_get_active_config: null Oct 26 08:37:21 volumio volumio[6074]: on_set_active_config: null Oct 26 08:37:21 volumio volumio[6074]: port: 5011 Oct 26 08:37:21 volumio volumio[6074]: supported_capture_types: null Oct 26 08:37:21 volumio volumio[6074]: supported_playback_types: null Oct 26 08:37:21 volumio volumio[6074]: update_config_symlink: false Oct 26 08:37:21 volumio volumio[6074]: update_config_txt: false Oct 26 08:37:21 volumio volumio[6074]: ======== Running on http://0.0.0.0:5011 ======== Oct 26 08:37:21 volumio volumio[6074]: (Press CTRL+C to quit) Oct 26 08:37:21 volumio systemd[1]: Stopping FusionDsp Daemon... Oct 26 08:37:21 volumio volumio[909]: info: Starting Shairport Sync Oct 26 08:37:22 volumio systemd[1]: fusiondsp.service: Succeeded. Oct 26 08:37:22 volumio systemd[1]: Stopped FusionDsp Daemon. Oct 26 08:37:22 volumio sudo[6554]: pam_unix(sudo:session): session closed for user root Oct 26 08:37:22 volumio mpd[6491]: Oct 26 08:37 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 26 08:37:22 volumio sudo[6568]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 26 08:37:22 volumio sudo[6568]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 08:37:22 volumio systemd[1]: Started Music Player Daemon. Oct 26 08:37:22 volumio sudo[6474]: pam_unix(sudo:session): session closed for user root Oct 26 08:37:22 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 26 08:37:22 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 26 08:37:22 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 26 08:37:22 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 26 08:37:22 volumio sudo[6568]: pam_unix(sudo:session): session closed for user root Oct 26 08:37:22 volumio sudo[6573]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 26 08:37:22 volumio sudo[6573]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 08:37:22 volumio sudo[6573]: pam_unix(sudo:session): session closed for user root Oct 26 08:37:22 volumio volumio[909]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/amd64 Oct 26 08:37:22 volumio volumio[909]: info: FusionDsp - Stopping FusionDsp service Oct 26 08:37:22 volumio sudo[6578]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 26 08:37:22 volumio sudo[6578]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 08:37:22 volumio volumio[909]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 26 08:37:22 volumio sudo[6578]: pam_unix(sudo:session): session closed for user root Oct 26 08:37:22 volumio volumio[909]: TypeError: Cannot read property 'stop' of null Oct 26 08:37:22 volumio volumio[909]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:109:23) Oct 26 08:37:22 volumio volumio[909]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Oct 26 08:37:22 volumio volumio[909]: at /volumio/app/pluginmanager.js:956:12 Oct 26 08:37:22 volumio volumio[909]: at ChildProcess.exithandler (child_process.js:374:7) Oct 26 08:37:22 volumio volumio[909]: at ChildProcess.emit (events.js:400:28) Oct 26 08:37:22 volumio volumio[909]: at maybeClose (internal/child_process.js:1088:16) Oct 26 08:37:22 volumio volumio[909]: at Socket. (internal/child_process.js:446:11) Oct 26 08:37:22 volumio volumio[909]: at Socket.emit (events.js:400:28) Oct 26 08:37:22 volumio volumio[909]: at Pipe. (net.js:686:12) Oct 26 08:37:22 volumio volumio[909]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 26 08:37:23 volumio sudo[6592]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-26 08:36 Oct 26 08:37:23 volumio sudo[6592]: 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="669bb6cc406bb77b11c5184fedb8a4dfd19ba4ec" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 11:08:46 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="9ef0bfa9b61509be20c9051562554f31"