-- Logs begin at Tue 2025-09-02 11:20:59 WIB, end at Tue 2025-09-02 11:31:28 WIB. -- Sep 02 11:30:00 hifi-streamer volumio[1200]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 02 11:30:00 hifi-streamer volumio[1200]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 02 11:30:00 hifi-streamer volumio[1200]: info: Discovery: Getting this device information Sep 02 11:30:00 hifi-streamer volumio[1200]: info: CoreCommandRouter::volumioGetState Sep 02 11:30:00 hifi-streamer volumio[1200]: info: CorePlayQueue::getTrack 10 Sep 02 11:30:00 hifi-streamer volumio[1200]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 02 11:30:00 hifi-streamer volumio[1200]: info: VolumeController::SetAlsaVolume91 Sep 02 11:30:00 hifi-streamer volumio[1200]: info: CoreCommandRouter::volumioGetState Sep 02 11:30:00 hifi-streamer volumio[1200]: info: CorePlayQueue::getTrack 10 Sep 02 11:30:00 hifi-streamer volumio[1200]: info: CoreStateMachine::pushState Sep 02 11:30:00 hifi-streamer volumio[1200]: info: CorePlayQueue::getTrack 10 Sep 02 11:30:00 hifi-streamer volumio[1200]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 11:30:00 hifi-streamer volumio[1200]: info: CoreCommandRouter::volumioPushState Sep 02 11:30:00 hifi-streamer volumio[1200]: SPOTIFY: RECEIVED VOLUMIO VOLUME 91 Sep 02 11:30:00 hifi-streamer volumio[1200]: SPOTIFY: SPOTIFY VOLUME 80 Sep 02 11:30:00 hifi-streamer volumio[1200]: SPOTIFY: VOLUMIO VOLUME 91 Sep 02 11:30:00 hifi-streamer volumio[1200]: SPOTIFY: DELTA VOLUME ENOUGH: true Sep 02 11:30:00 hifi-streamer volumio[1200]: info: Setting Spotify Volume from Volumio: 91 Sep 02 11:30:01 hifi-streamer volumio[1200]: info: VolumeController::SetAlsaVolume97 Sep 02 11:30:01 hifi-streamer volumio[1200]: info: CoreCommandRouter::volumioGetState Sep 02 11:30:01 hifi-streamer volumio[1200]: info: CorePlayQueue::getTrack 10 Sep 02 11:30:01 hifi-streamer volumio[1200]: info: CoreStateMachine::pushState Sep 02 11:30:01 hifi-streamer volumio[1200]: info: CorePlayQueue::getTrack 10 Sep 02 11:30:01 hifi-streamer volumio[1200]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 11:30:01 hifi-streamer volumio[1200]: info: CoreCommandRouter::volumioPushState Sep 02 11:30:01 hifi-streamer volumio[1200]: SPOTIFY: RECEIVED VOLUMIO VOLUME 97 Sep 02 11:30:01 hifi-streamer volumio[1200]: SPOTIFY: SPOTIFY VOLUME 91 Sep 02 11:30:01 hifi-streamer volumio[1200]: SPOTIFY: VOLUMIO VOLUME 97 Sep 02 11:30:01 hifi-streamer volumio[1200]: SPOTIFY: DELTA VOLUME ENOUGH: true Sep 02 11:30:01 hifi-streamer volumio[1200]: info: Setting Spotify Volume from Volumio: 97 Sep 02 11:30:02 hifi-streamer volumiologrotate[830]: ls: cannot access '/var/log/samba/log.wb-HIFI': No such file or directory Sep 02 11:30:02 hifi-streamer volumiologrotate[830]: ls: cannot access 'STREAMER': No such file or directory Sep 02 11:30:02 hifi-streamer volumio[1200]: SPOTIFY: SETTING SPOTIFY VOLUME 97 Sep 02 11:30:02 hifi-streamer volumio[1200]: info: Sending Spotify command with payload to local API: /player/volume Sep 02 11:30:30 hifi-streamer volumio[1200]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 02 11:30:30 hifi-streamer volumio[1200]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 02 11:30:30 hifi-streamer volumio[1200]: info: Discovery: Getting this device information Sep 02 11:30:30 hifi-streamer volumio[1200]: info: CoreCommandRouter::volumioGetState Sep 02 11:30:30 hifi-streamer volumio[1200]: info: CorePlayQueue::getTrack 10 Sep 02 11:30:30 hifi-streamer volumio[1200]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 02 11:31:00 hifi-streamer volumio[1200]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 02 11:31:00 hifi-streamer volumio[1200]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 02 11:31:00 hifi-streamer volumio[1200]: info: Discovery: Getting this device information Sep 02 11:31:00 hifi-streamer volumio[1200]: info: CoreCommandRouter::volumioGetState Sep 02 11:31:00 hifi-streamer volumio[1200]: info: CorePlayQueue::getTrack 10 Sep 02 11:31:00 hifi-streamer volumio[1200]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 02 11:31:02 hifi-streamer volumiologrotate[830]: ls: cannot access '/var/log/samba/log.wb-HIFI': No such file or directory Sep 02 11:31:02 hifi-streamer volumiologrotate[830]: ls: cannot access 'STREAMER': No such file or directory Sep 02 11:31:14 hifi-streamer volumio[1200]: info: VolumeController::SetAlsaVolume90 Sep 02 11:31:14 hifi-streamer volumio[1200]: info: CoreCommandRouter::volumioGetState Sep 02 11:31:14 hifi-streamer volumio[1200]: info: CorePlayQueue::getTrack 10 Sep 02 11:31:14 hifi-streamer volumio[1200]: info: CoreStateMachine::pushState Sep 02 11:31:14 hifi-streamer volumio[1200]: info: CorePlayQueue::getTrack 10 Sep 02 11:31:14 hifi-streamer volumio[1200]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 11:31:14 hifi-streamer volumio[1200]: info: CoreCommandRouter::volumioPushState Sep 02 11:31:14 hifi-streamer volumio[1200]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Sep 02 11:31:14 hifi-streamer volumio[1200]: SPOTIFY: SPOTIFY VOLUME 97 Sep 02 11:31:14 hifi-streamer volumio[1200]: SPOTIFY: VOLUMIO VOLUME 90 Sep 02 11:31:14 hifi-streamer volumio[1200]: SPOTIFY: DELTA VOLUME ENOUGH: true Sep 02 11:31:14 hifi-streamer volumio[1200]: info: Setting Spotify Volume from Volumio: 90 Sep 02 11:31:16 hifi-streamer volumio[1200]: SPOTIFY: SETTING SPOTIFY VOLUME 90 Sep 02 11:31:16 hifi-streamer volumio[1200]: info: Sending Spotify command with payload to local API: /player/volume Sep 02 11:31:20 hifi-streamer volumio[1200]: info: CoreCommandRouter::volumioGetState Sep 02 11:31:20 hifi-streamer volumio[1200]: info: CorePlayQueue::getTrack 10 Sep 02 11:31:22 hifi-streamer volumio[1200]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 02 11:31:22 hifi-streamer volumio[1200]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Sep 02 11:31:22 hifi-streamer go-librespot[1638]: time="2025-09-02T11:31:22+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Sep 02 11:31:22 hifi-streamer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 02 11:31:22 hifi-streamer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 02 11:31:22 hifi-streamer volumio[1200]: error: Failed to send command to Spotify local API: /player/volume: Error: socket hang up Sep 02 11:31:22 hifi-streamer volumio[1200]: error: Failed to send command to Spotify local API: /player/volume: Error: socket hang up Sep 02 11:31:22 hifi-streamer volumio[1200]: error: Failed to send command to Spotify local API: /player/volume: Error: socket hang up Sep 02 11:31:22 hifi-streamer volumio[1200]: error: Failed to send command to Spotify local API: /player/volume: Error: socket hang up Sep 02 11:31:22 hifi-streamer volumio[1200]: error: Failed to send command to Spotify local API: /player/volume: Error: socket hang up Sep 02 11:31:22 hifi-streamer volumio[1200]: error: Failed to send command to Spotify local API: /player/volume: Error: socket hang up Sep 02 11:31:22 hifi-streamer volumio[1200]: error: Failed to send command to Spotify local API: /player/volume: Error: socket hang up Sep 02 11:31:22 hifi-streamer volumio[1200]: error: Failed to send command to Spotify local API: /player/volume: Error: socket hang up Sep 02 11:31:22 hifi-streamer volumio[1200]: error: Failed to send command to Spotify local API: /player/volume: Error: socket hang up Sep 02 11:31:22 hifi-streamer volumio[1200]: error: Failed to send command to Spotify local API: /player/volume: Error: socket hang up Sep 02 11:31:22 hifi-streamer volumio[1200]: error: Failed to send command to Spotify local API: /player/volume: Error: socket hang up Sep 02 11:31:22 hifi-streamer volumio[1200]: error: Failed to send command to Spotify local API: /player/volume: Error: socket hang up Sep 02 11:31:22 hifi-streamer volumio[1200]: error: Failed to send command to Spotify local API: /player/volume: Error: socket hang up Sep 02 11:31:22 hifi-streamer volumio[1200]: error: Failed to send command to Spotify local API: /player/volume: Error: socket hang up Sep 02 11:31:22 hifi-streamer volumio[1200]: error: Failed to send command to Spotify local API: /player/volume: Error: socket hang up Sep 02 11:31:22 hifi-streamer volumio[1200]: error: Failed to send command to Spotify local API: /player/volume: Error: socket hang up Sep 02 11:31:22 hifi-streamer volumio[1200]: error: Failed to send command to Spotify local API: /player/volume: Error: socket hang up Sep 02 11:31:22 hifi-streamer volumio[1200]: error: Failed to send command to Spotify local API: /player/volume: Error: socket hang up Sep 02 11:31:22 hifi-streamer volumio[1200]: error: Failed to send command to Spotify local API: /player/volume: Error: socket hang up Sep 02 11:31:22 hifi-streamer volumio[1200]: error: Failed to send command to Spotify local API: /player/volume: Error: socket hang up Sep 02 11:31:22 hifi-streamer volumio[1200]: error: Failed to send command to Spotify local API: /player/volume: Error: socket hang up Sep 02 11:31:22 hifi-streamer volumio[1200]: error: Failed to send command to Spotify local API: /player/volume: Error: socket hang up Sep 02 11:31:22 hifi-streamer volumio[1200]: error: Failed to send command to Spotify local API: /player/volume: Error: socket hang up Sep 02 11:31:22 hifi-streamer volumio[1200]: error: Failed to send command to Spotify local API: /player/volume: Error: socket hang up Sep 02 11:31:22 hifi-streamer volumio[1200]: error: Failed to send command to Spotify local API: /player/volume: Error: socket hang up Sep 02 11:31:22 hifi-streamer volumio[1200]: error: Failed to send command to Spotify local API: /player/volume: Error: socket hang up Sep 02 11:31:22 hifi-streamer volumio[1200]: error: Failed to send command to Spotify local API: /player/volume: Error: socket hang up Sep 02 11:31:22 hifi-streamer volumio[1200]: error: Failed to send command to Spotify local API: /player/volume: Error: socket hang up Sep 02 11:31:22 hifi-streamer volumio[1200]: error: Failed to send command to Spotify local API: /player/volume: Error: socket hang up Sep 02 11:31:22 hifi-streamer volumio[1200]: error: Failed to send command to Spotify local API: /player/volume: Error: socket hang up Sep 02 11:31:22 hifi-streamer volumio[1200]: error: Failed to send command to Spotify local API: /player/volume: Error: socket hang up Sep 02 11:31:22 hifi-streamer volumio[1200]: (node:1200) UnhandledPromiseRejectionWarning: Error: socket hang up Sep 02 11:31:22 hifi-streamer volumio[1200]: at connResetException (internal/errors.js:639:14) Sep 02 11:31:22 hifi-streamer volumio[1200]: at Socket.socketOnEnd (_http_client.js:499:23) Sep 02 11:31:22 hifi-streamer volumio[1200]: at Socket.emit (events.js:412:35) Sep 02 11:31:22 hifi-streamer volumio[1200]: at endReadableNT (internal/streams/readable.js:1333:12) Sep 02 11:31:22 hifi-streamer volumio[1200]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Sep 02 11:31:22 hifi-streamer volumio[1200]: (Use `node --trace-warnings ...` to show where the warning was created) Sep 02 11:31:22 hifi-streamer volumio[1200]: (node:1200) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 1) Sep 02 11:31:22 hifi-streamer volumio[1200]: (node:1200) [DEP0018] DeprecationWarning: Unhandled promise rejections are deprecated. In the future, promise rejections that are not handled will terminate the Node.js process with a non-zero exit code. Sep 02 11:31:22 hifi-streamer volumio[1200]: error: Failed to send command to Spotify local API: /player/volume: Error: socket hang up Sep 02 11:31:22 hifi-streamer volumio[1200]: error: Failed to send command to Spotify local API: /player/volume: Error: socket hang up Sep 02 11:31:22 hifi-streamer volumio[1200]: info: Connection to go-librespot Websocket closed Sep 02 11:31:25 hifi-streamer volumio[1200]: info: Initializing connection to go-librespot Websocket Sep 02 11:31:25 hifi-streamer volumio[1200]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 02 11:31:25 hifi-streamer volumio[1200]: info: VolumeController::SetAlsaVolume86 Sep 02 11:31:25 hifi-streamer volumio[1200]: info: CoreCommandRouter::volumioGetState Sep 02 11:31:25 hifi-streamer volumio[1200]: info: CorePlayQueue::getTrack 10 Sep 02 11:31:25 hifi-streamer volumio[1200]: info: CoreStateMachine::pushState Sep 02 11:31:25 hifi-streamer volumio[1200]: info: CorePlayQueue::getTrack 10 Sep 02 11:31:25 hifi-streamer volumio[1200]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 11:31:25 hifi-streamer volumio[1200]: info: CoreCommandRouter::volumioPushState Sep 02 11:31:25 hifi-streamer systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 02 11:31:25 hifi-streamer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Sep 02 11:31:25 hifi-streamer systemd[1]: Stopped go-librespot Daemon. Sep 02 11:31:25 hifi-streamer volumio[1200]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Sep 02 11:31:25 hifi-streamer volumio[1200]: SPOTIFY: SPOTIFY VOLUME 90 Sep 02 11:31:25 hifi-streamer volumio[1200]: SPOTIFY: VOLUMIO VOLUME 86 Sep 02 11:31:25 hifi-streamer volumio[1200]: SPOTIFY: DELTA VOLUME ENOUGH: true Sep 02 11:31:25 hifi-streamer volumio[1200]: info: Setting Spotify Volume from Volumio: 86 Sep 02 11:31:25 hifi-streamer systemd[1]: Started go-librespot Daemon. Sep 02 11:31:25 hifi-streamer go-librespot[3535]: go-librespot daemon starting... Sep 02 11:31:25 hifi-streamer go-librespot[3535]: time="2025-09-02T11:31:25+07:00" level=info msg="running go-librespot 0.3.2" Sep 02 11:31:25 hifi-streamer go-librespot[3535]: time="2025-09-02T11:31:25+07:00" level=debug msg="app state loaded" Sep 02 11:31:25 hifi-streamer go-librespot[3535]: time="2025-09-02T11:31:25+07:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 02 11:31:26 hifi-streamer go-librespot[3535]: time="2025-09-02T11:31:26+07:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Sep 02 11:31:26 hifi-streamer go-librespot[3535]: time="2025-09-02T11:31:26+07:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 02 11:31:26 hifi-streamer go-librespot[3535]: time="2025-09-02T11:31:26+07:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 02 11:31:26 hifi-streamer go-librespot[3535]: time="2025-09-02T11:31:26+07:00" level=info msg="zeroconf server listening on port 44745" Sep 02 11:31:26 hifi-streamer volumio[1200]: info: VolumeController::SetAlsaVolume79 Sep 02 11:31:26 hifi-streamer volumio[1200]: info: CoreCommandRouter::volumioGetState Sep 02 11:31:26 hifi-streamer volumio[1200]: info: CorePlayQueue::getTrack 10 Sep 02 11:31:26 hifi-streamer volumio[1200]: info: CoreStateMachine::pushState Sep 02 11:31:26 hifi-streamer volumio[1200]: info: CorePlayQueue::getTrack 10 Sep 02 11:31:26 hifi-streamer volumio[1200]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 11:31:26 hifi-streamer volumio[1200]: info: CoreCommandRouter::volumioPushState Sep 02 11:31:26 hifi-streamer volumio[1200]: SPOTIFY: RECEIVED VOLUMIO VOLUME 79 Sep 02 11:31:26 hifi-streamer volumio[1200]: SPOTIFY: SPOTIFY VOLUME 86 Sep 02 11:31:26 hifi-streamer volumio[1200]: SPOTIFY: VOLUMIO VOLUME 79 Sep 02 11:31:26 hifi-streamer volumio[1200]: SPOTIFY: DELTA VOLUME ENOUGH: true Sep 02 11:31:26 hifi-streamer volumio[1200]: info: Setting Spotify Volume from Volumio: 79 Sep 02 11:31:26 hifi-streamer go-librespot[3535]: time="2025-09-02T11:31:26+07:00" level=debug msg="obtained new client token: AAB09PPRsGab9Ljz/1fBhtZpMj2LhU7tlPugtT0YS1oZqVC2rkTahDmPGUqLtw0dHKTdBP1A7FIwLSbb+/l25zuqTK+1pq1ynCFiojtcxQCBhI+xA5+TP6Pr6+xzfp7vALou9fqCf6LxfbEr4CRp5Wgv19prOUcP+V1QWFQj0sHme3wbYnOTyu6/1tnQVyoSODUAukkyhECLqYRgoCJDoz5Smy8AuDagS3SLV+a0yv8Tu/J36gRaEn8z5A==" Sep 02 11:31:26 hifi-streamer go-librespot[3535]: time="2025-09-02T11:31:26+07:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 02 11:31:26 hifi-streamer go-librespot[3535]: time="2025-09-02T11:31:26+07:00" level=debug msg="completed keyexchange" Sep 02 11:31:26 hifi-streamer go-librespot[3535]: time="2025-09-02T11:31:26+07:00" level=debug msg="completed challenge" Sep 02 11:31:26 hifi-streamer go-librespot[3535]: time="2025-09-02T11:31:26+07:00" level=info msg="authenticated AP" username="21*********************ma" Sep 02 11:31:27 hifi-streamer go-librespot[3535]: time="2025-09-02T11:31:27+07:00" level=info msg="authenticated Login5" username="21*********************ma" Sep 02 11:31:27 hifi-streamer go-librespot[3535]: time="2025-09-02T11:31:27+07:00" level=debug msg="initializing zeroconf session" username="21*********************ma" Sep 02 11:31:27 hifi-streamer go-librespot[3535]: time="2025-09-02T11:31:27+07:00" level=debug msg="dealer connection opened" Sep 02 11:31:27 hifi-streamer go-librespot[3535]: time="2025-09-02T11:31:27+07:00" level=trace msg="starting accesspoint recv loop" Sep 02 11:31:27 hifi-streamer go-librespot[3535]: time="2025-09-02T11:31:27+07:00" level=trace msg="starting dealer recv loop" Sep 02 11:31:27 hifi-streamer go-librespot[3535]: time="2025-09-02T11:31:27+07:00" level=trace msg="received accesspoint ping" Sep 02 11:31:27 hifi-streamer go-librespot[3535]: time="2025-09-02T11:31:27+07:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Sep 02 11:31:27 hifi-streamer go-librespot[3535]: time="2025-09-02T11:31:27+07:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Sep 02 11:31:27 hifi-streamer go-librespot[3535]: time="2025-09-02T11:31:27+07:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Sep 02 11:31:27 hifi-streamer go-librespot[3535]: time="2025-09-02T11:31:27+07:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Sep 02 11:31:27 hifi-streamer go-librespot[3535]: time="2025-09-02T11:31:27+07:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Sep 02 11:31:27 hifi-streamer go-librespot[3535]: time="2025-09-02T11:31:27+07:00" level=debug msg="received connection id: MmQwMDE4ZTktY2Q1...QTkwNzgwMTNGRA==" Sep 02 11:31:27 hifi-streamer volumio[1200]: info: Enabling plugin fusiondsp Sep 02 11:31:27 hifi-streamer volumio[1200]: info: Loading plugin "fusiondsp"... Sep 02 11:31:27 hifi-streamer go-librespot[3535]: time="2025-09-02T11:31:27+07:00" level=trace msg="received accesspoint pong ack" Sep 02 11:31:27 hifi-streamer volumio[1200]: info: Applying required configuration parameters for plugin fusiondsp Sep 02 11:31:27 hifi-streamer volumio[1200]: info: Preparing to generate the ALSA configuration file Sep 02 11:31:27 hifi-streamer volumio[1200]: SPOTIFY: SETTING SPOTIFY VOLUME 79 Sep 02 11:31:27 hifi-streamer volumio[1200]: info: Sending Spotify command with payload to local API: /player/volume Sep 02 11:31:27 hifi-streamer volumio[1200]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 02 11:31:27 hifi-streamer volumio[1200]: info: Reading ALSA contributions from plugins. Sep 02 11:31:27 hifi-streamer volumio[1200]: info: Asound.conf file written Sep 02 11:31:27 hifi-streamer sudo[3560]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Sep 02 11:31:27 hifi-streamer sudo[3560]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 11:31:27 hifi-streamer sudo[3560]: pam_unix(sudo:session): session closed for user root Sep 02 11:31:27 hifi-streamer volumio[1200]: info: Output device has changed, restarting MPD Sep 02 11:31:27 hifi-streamer sudo[3566]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 02 11:31:27 hifi-streamer sudo[3566]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 11:31:27 hifi-streamer sudo[3566]: pam_unix(sudo:session): session closed for user root Sep 02 11:31:27 hifi-streamer volumio[1200]: info: PLUGIN START: fusiondsp Sep 02 11:31:27 hifi-streamer volumio[1200]: info: Loading i18n strings for locale en Sep 02 11:31:27 hifi-streamer sudo[3569]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 02 11:31:27 hifi-streamer sudo[3569]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 11:31:27 hifi-streamer volumio[1200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 02 11:31:27 hifi-streamer volumio[1200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 11:31:27 hifi-streamer volumio[1200]: info: FusionDsp - mixtype--------------------- Hardware Sep 02 11:31:27 hifi-streamer volumio[1200]: info: Preparing to generate the ALSA configuration file Sep 02 11:31:27 hifi-streamer systemd[1]: Stopping Music Player Daemon... Sep 02 11:31:27 hifi-streamer volumio[1200]: info: Done. Sep 02 11:31:27 hifi-streamer volumio[1200]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 02 11:31:27 hifi-streamer volumio[1200]: info: Reading ALSA contributions from plugins. Sep 02 11:31:27 hifi-streamer volumio[1200]: info: MPD Permissions set Sep 02 11:31:27 hifi-streamer volumio[1200]: info: CoreCommandRouter::volumioGetState Sep 02 11:31:27 hifi-streamer volumio[1200]: info: CorePlayQueue::getTrack 10 Sep 02 11:31:27 hifi-streamer volumio[1200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 11:31:27 hifi-streamer systemd[1]: mpd.service: Succeeded. Sep 02 11:31:27 hifi-streamer volumio[1200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 11:31:27 hifi-streamer volumio[1200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 11:31:27 hifi-streamer volumio[1200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 11:31:27 hifi-streamer volumio[1200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 11:31:27 hifi-streamer volumio[1200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 11:31:27 hifi-streamer systemd[1]: Stopped Music Player Daemon. Sep 02 11:31:27 hifi-streamer volumio[1200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 11:31:27 hifi-streamer volumio[1200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 11:31:27 hifi-streamer volumio[1200]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 11:31:27 hifi-streamer volumio[1200]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 02 11:31:27 hifi-streamer volumio[1200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 11:31:27 hifi-streamer volumio[1200]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 02 11:31:27 hifi-streamer volumio[1200]: info: Asound.conf file unchanged, so no further update is needed Sep 02 11:31:27 hifi-streamer volumio[1200]: info: Output device has changed, restarting MPD Sep 02 11:31:28 hifi-streamer systemd[1]: Starting Music Player Daemon... Sep 02 11:31:28 hifi-streamer sudo[3579]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 02 11:31:28 hifi-streamer sudo[3579]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 11:31:28 hifi-streamer sudo[3579]: pam_unix(sudo:session): session closed for user root Sep 02 11:31:28 hifi-streamer sudo[3582]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 02 11:31:28 hifi-streamer volumio[1200]: info: FusionDsp - no preset used Sep 02 11:31:28 hifi-streamer sudo[3582]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 11:31:28 hifi-streamer sudo[3571]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 02 11:31:28 hifi-streamer sudo[3571]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 11:31:28 hifi-streamer sudo[3571]: pam_unix(sudo:session): session closed for user root Sep 02 11:31:28 hifi-streamer systemd[1]: mpd.service: Succeeded. Sep 02 11:31:28 hifi-streamer systemd[1]: Stopped Music Player Daemon. Sep 02 11:31:28 hifi-streamer systemd[1]: Starting Music Player Daemon... Sep 02 11:31:28 hifi-streamer sudo[3585]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 02 11:31:28 hifi-streamer sudo[3585]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 11:31:28 hifi-streamer sudo[3585]: pam_unix(sudo:session): session closed for user root Sep 02 11:31:28 hifi-streamer go-librespot[3535]: time="2025-09-02T11:31:28+07:00" level=debug msg="put connect state because NEW_DEVICE" Sep 02 11:31:28 hifi-streamer go-librespot[3535]: time="2025-09-02T11:31:28+07:00" level=debug msg="update volume requested to 51772/65535" Sep 02 11:31:28 hifi-streamer volumio[1200]: info: MPD Permissions set Sep 02 11:31:28 hifi-streamer volumio[1200]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Sep 02 11:31:28 hifi-streamer volumio[1200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 11:31:28 hifi-streamer volumio[1200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 11:31:28 hifi-streamer volumio[1200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 11:31:28 hifi-streamer volumio[1200]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 11:31:28 hifi-streamer volumio[1200]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 02 11:31:28 hifi-streamer volumio[1200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 11:31:28 hifi-streamer mpd[3587]: Sep 02 11:31 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 02 11:31:28 hifi-streamer systemd[1]: Started Music Player Daemon. Sep 02 11:31:28 hifi-streamer sudo[3582]: pam_unix(sudo:session): session closed for user root Sep 02 11:31:28 hifi-streamer sudo[3569]: pam_unix(sudo:session): session closed for user root Sep 02 11:31:28 hifi-streamer volumio[1200]: error: MPD error: The expression evaluated to a falsy value: Sep 02 11:31:28 hifi-streamer volumio[1200]: assert.ok(self.idling) Sep 02 11:31:28 hifi-streamer volumio[1200]: error: The expression evaluated to a falsy value: Sep 02 11:31:28 hifi-streamer volumio[1200]: assert.ok(self.idling) Sep 02 11:31:28 hifi-streamer volumio[1200]: error: updateQueue error: null Sep 02 11:31:28 hifi-streamer volumio[1200]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 02 11:31:28 hifi-streamer volumio[1200]: TypeError: Cannot read property 'off' of undefined Sep 02 11:31:28 hifi-streamer volumio[1200]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:107:15) Sep 02 11:31:28 hifi-streamer volumio[1200]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Sep 02 11:31:28 hifi-streamer volumio[1200]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1883:8) Sep 02 11:31:28 hifi-streamer volumio[1200]: at CoreCommandRouter.disableAndStopPlugin (/volumio/app/index.js:1556:29) Sep 02 11:31:28 hifi-streamer volumio[1200]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1401:47) Sep 02 11:31:28 hifi-streamer volumio[1200]: at Socket.emit (events.js:400:28) Sep 02 11:31:28 hifi-streamer volumio[1200]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Sep 02 11:31:28 hifi-streamer volumio[1200]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Sep 02 11:31:28 hifi-streamer volumio[1200]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 02 11:31:28 hifi-streamer sudo[3606]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-02 11:30 Sep 02 11:31:28 hifi-streamer sudo[3606]: 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"