-- Logs begin at Thu 2019-02-14 02:11:58 PST, end at Tue 2025-09-02 17:27:07 PDT. -- Sep 02 17:26:07 hub volumio[1000]: info: Enabling plugin peppyspectrum Sep 02 17:26:07 hub volumio[1000]: info: Loading plugin "peppyspectrum"... Sep 02 17:26:07 hub volumio[1000]: info: Preparing to generate the ALSA configuration file Sep 02 17:26:07 hub volumio[1000]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Sep 02 17:26:07 hub volumio[1000]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 02 17:26:07 hub volumio[1000]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Sep 02 17:26:07 hub volumio[1000]: info: Reading ALSA contributions from plugins. Sep 02 17:26:07 hub volumio[1000]: info: Asound.conf file written Sep 02 17:26:07 hub sudo[2391]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Sep 02 17:26:07 hub sudo[2391]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 17:26:07 hub sudo[2391]: pam_unix(sudo:session): session closed for user root Sep 02 17:26:07 hub volumio[1000]: info: Output device has changed, restarting MPD Sep 02 17:26:07 hub sudo[2397]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 02 17:26:07 hub sudo[2397]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 17:26:07 hub sudo[2397]: pam_unix(sudo:session): session closed for user root Sep 02 17:26:07 hub volumio[1000]: info: Output device has changed, restarting Shairport Sync Sep 02 17:26:07 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 17:26:07 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 17:26:07 hub sudo[2400]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 02 17:26:07 hub sudo[2400]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 17:26:07 hub systemd[1]: Stopping Music Player Daemon... Sep 02 17:26:07 hub systemd[1]: mpd.service: Succeeded. Sep 02 17:26:07 hub volumio[1000]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 02 17:26:07 hub systemd[1]: Stopped Music Player Daemon. Sep 02 17:26:07 hub volumio[1000]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Sep 02 17:26:07 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 17:26:07 hub volumio[1000]: info: QobuzConnect: setDeactiveState invoked Sep 02 17:26:07 hub volumio[1000]: info: CoreCommandRouter::volumioGetState Sep 02 17:26:07 hub volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 02 17:26:07 hub vtcs[1802]: [2025-09-02 17:26:07.940] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Sep 02 17:26:07 hub sudo[2409]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Sep 02 17:26:07 hub systemd[1]: Starting Music Player Daemon... Sep 02 17:26:07 hub volumio[1000]: info: PLUGIN START: peppyspectrum Sep 02 17:26:07 hub vtcs[1802]: [2025-09-02 17:26:07.941] [tisoc] [error] [SpkconServer.cpp:380] recv error. client fd=5 errorno=104 error=Connection reset by peer Sep 02 17:26:07 hub vtcs[1802]: [2025-09-02 17:26:07.941] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Sep 02 17:26:07 hub sudo[2409]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 17:26:07 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 02 17:26:08 hub systemd[1]: Stopping Volumio Tidal Connect Service... Sep 02 17:26:08 hub systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Sep 02 17:26:08 hub systemd[1]: vtcs.service: Succeeded. Sep 02 17:26:08 hub systemd[1]: Stopped Volumio Tidal Connect Service. Sep 02 17:26:08 hub sudo[2409]: pam_unix(sudo:session): session closed for user root Sep 02 17:26:08 hub volumio[1000]: info: Preparing to generate the ALSA configuration file Sep 02 17:26:08 hub volumio[1000]: info: Done. Sep 02 17:26:08 hub sudo[2405]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 02 17:26:08 hub sudo[2405]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 17:26:08 hub sudo[2405]: pam_unix(sudo:session): session closed for user root Sep 02 17:26:08 hub sudo[2415]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Sep 02 17:26:08 hub sudo[2415]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 17:26:08 hub sudo[2415]: pam_unix(sudo:session): session closed for user root Sep 02 17:26:08 hub volumio[1000]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Sep 02 17:26:08 hub volumio[1000]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 02 17:26:08 hub volumio[1000]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Sep 02 17:26:08 hub volumio[1000]: info: Reading ALSA contributions from plugins. Sep 02 17:26:08 hub sudo[2422]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Sep 02 17:26:08 hub sudo[2422]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 17:26:08 hub volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 02 17:26:08 hub volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 02 17:26:08 hub volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 02 17:26:08 hub sudo[2422]: pam_unix(sudo:session): session closed for user root Sep 02 17:26:08 hub volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 02 17:26:08 hub sudo[2424]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Sep 02 17:26:08 hub sudo[2424]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 17:26:08 hub volumio[1000]: info: Enabling plugin peppyspectrum Sep 02 17:26:08 hub volumio[1000]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 02 17:26:08 hub volumio[1000]: info: Loading plugin "peppyspectrum"... Sep 02 17:26:08 hub systemd[1]: Stopping Volumio Qobuz Connect Service... Sep 02 17:26:08 hub volumio[1000]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Sep 02 17:26:08 hub qobuz-connect[1744]: 20250902 17:26:08.356 [1744.1744] INFO SampleApp: Stopping Local configuration server Sep 02 17:26:08 hub volumio[1000]: info: MPD Permissions set Sep 02 17:26:08 hub qobuz-connect[1744]: 20250902 17:26:08.368 [1744.1744] INFO SampleApp: shat down connection on UNIX socket Sep 02 17:26:08 hub volumio[1000]: info: CoreCommandRouter::volumioGetState Sep 02 17:26:08 hub systemd[1]: qobuz-connect.service: Succeeded. Sep 02 17:26:08 hub volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 02 17:26:08 hub systemd[1]: Stopped Volumio Qobuz Connect Service. Sep 02 17:26:08 hub volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 02 17:26:08 hub volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 02 17:26:08 hub volumio[1000]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Sep 02 17:26:08 hub volumio[1000]: info: QobuzConnect: setDeactiveState invoked Sep 02 17:26:08 hub volumio[1000]: info: CoreCommandRouter::volumioGetState Sep 02 17:26:08 hub volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 02 17:26:08 hub volumio[1000]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Sep 02 17:26:08 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 17:26:08 hub go-librespot[1331]: time="2025-09-02T17:26:08-07:00" level=trace msg="sent dealer ping" Sep 02 17:26:08 hub systemd[1]: Started Volumio Qobuz Connect Service. Sep 02 17:26:08 hub sudo[2424]: pam_unix(sudo:session): session closed for user root Sep 02 17:26:08 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 17:26:08 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 17:26:08 hub go-librespot[1331]: time="2025-09-02T17:26:08-07:00" level=trace msg="received dealer pong" Sep 02 17:26:08 hub volumio[1000]: info: FusionDsp - no preset used Sep 02 17:26:08 hub volumio[1000]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json,Bass kinda?.json,Normal.json Sep 02 17:26:08 hub volumio[1000]: info: Executing endpoint qc_getconfig Sep 02 17:26:08 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Sep 02 17:26:08 hub qobuz-connect[2426]: 20250902 17:26:08.729 [2426.2426] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Sep 02 17:26:08 hub volumio[1000]: info: Starting Shairport Sync Sep 02 17:26:08 hub qobuz-connect[2426]: 20250902 17:26:08.737 [2426.2426] INFO VolumeManager: [0xb26a70]: Setting new playback volume: 75 Sep 02 17:26:08 hub qobuz-connect[2426]: 20250902 17:26:08.737 [2426.2426] INFO VolumeManager: [0xb26a70]: Setting new mute state: 0 Sep 02 17:26:08 hub qobuz-connect[2426]: 20250902 17:26:08.737 [2426.2426] INFO QobuzConnect: [0xb27440]: Client initialized! Sep 02 17:26:08 hub qobuz-connect[2426]: 20250902 17:26:08.737 [2426.2426] INFO SampleApp: Starting Avahi advertising, name: Hub, service name: _qobuz-connect._tcp Sep 02 17:26:08 hub qobuz-connect[2426]: 20250902 17:26:08.751 [2426.2426] INFO LocalConfigManager: [0xb26350]: Starting Local Configuration server Sep 02 17:26:08 hub qobuz-connect[2426]: 20250902 17:26:08.751 [2426.2426] INFO SampleApp: Starting Local configuration server Sep 02 17:26:08 hub qobuz-connect[2426]: 20250902 17:26:08.751 [2426.2426] INFO SampleApp: Connected to UNIX socket client 0xb1bf60 Sep 02 17:26:08 hub volumio[1000]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Sep 02 17:26:08 hub volumio[1000]: info: QobuzConnect: QOBUZ Connect daemon connected Sep 02 17:26:08 hub sudo[2434]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 02 17:26:08 hub sudo[2434]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 17:26:08 hub volumio[1000]: info: Asound.conf file unchanged, so no further update is needed Sep 02 17:26:08 hub volumio[1000]: info: Output device has changed, restarting MPD Sep 02 17:26:08 hub systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 02 17:26:08 hub systemd[1]: shairport-sync.service: Succeeded. Sep 02 17:26:08 hub systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 02 17:26:08 hub sudo[2438]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 02 17:26:08 hub sudo[2438]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 17:26:08 hub qobuz-connect[2426]: 20250902 17:26:08.883 [2426.2426] INFO SampleApp: Playback volume changed: 75 Sep 02 17:26:08 hub volumio[1000]: info: Output device has changed, restarting Shairport Sync Sep 02 17:26:08 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 17:26:08 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 17:26:08 hub systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 02 17:26:08 hub sudo[2438]: pam_unix(sudo:session): session closed for user root Sep 02 17:26:08 hub sudo[2441]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 02 17:26:08 hub sudo[2441]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 17:26:08 hub sudo[2434]: pam_unix(sudo:session): session closed for user root Sep 02 17:26:08 hub volumio[1000]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 02 17:26:08 hub volumio[1000]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Sep 02 17:26:08 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 17:26:08 hub systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 02 17:26:08 hub systemd[1]: mpd.service: Succeeded. Sep 02 17:26:08 hub systemd[1]: Stopped Music Player Daemon. Sep 02 17:26:08 hub systemd[1]: Starting Music Player Daemon... Sep 02 17:26:08 hub volumio[1000]: info: QobuzConnect: setDeactiveState invoked Sep 02 17:26:08 hub volumio[1000]: info: CoreCommandRouter::volumioGetState Sep 02 17:26:08 hub volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 02 17:26:08 hub volumio[1000]: info: Preparing to generate the ALSA configuration file Sep 02 17:26:08 hub sudo[2452]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Sep 02 17:26:08 hub sudo[2452]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 17:26:09 hub sudo[2449]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 02 17:26:09 hub sudo[2449]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 17:26:09 hub sudo[2449]: pam_unix(sudo:session): session closed for user root Sep 02 17:26:09 hub sudo[2452]: pam_unix(sudo:session): session closed for user root Sep 02 17:26:09 hub sudo[2461]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Sep 02 17:26:09 hub sudo[2461]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 17:26:09 hub sudo[2461]: pam_unix(sudo:session): session closed for user root Sep 02 17:26:09 hub volumio[1000]: info: Stopping peppyspectrum service Sep 02 17:26:09 hub volumio[1000]: info: CoreStateMachine::stop Sep 02 17:26:09 hub volumio[1000]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 02 17:26:09 hub sudo[2468]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Sep 02 17:26:09 hub sudo[2468]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 17:26:09 hub sudo[2468]: pam_unix(sudo:session): session closed for user root Sep 02 17:26:09 hub volumio[1000]: info: Disabling plugin peppyspectrum Sep 02 17:26:09 hub volumio[1000]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Sep 02 17:26:09 hub volumio[1000]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 02 17:26:09 hub sudo[2471]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Sep 02 17:26:09 hub sudo[2471]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 17:26:09 hub sudo[2472]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Sep 02 17:26:09 hub volumio[1000]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Sep 02 17:26:09 hub sudo[2472]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 17:26:09 hub volumio[1000]: info: Reading ALSA contributions from plugins. Sep 02 17:26:09 hub volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 02 17:26:09 hub volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 02 17:26:09 hub volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 02 17:26:09 hub volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 02 17:26:09 hub volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 02 17:26:09 hub volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 02 17:26:09 hub systemd[1]: Stopping Volumio Qobuz Connect Service... Sep 02 17:26:09 hub qobuz-connect[2426]: 20250902 17:26:09.214 [2426.2426] INFO SampleApp: Stopping Local configuration server Sep 02 17:26:09 hub sudo[2472]: pam_unix(sudo:session): session closed for user root Sep 02 17:26:09 hub qobuz-connect[2426]: 20250902 17:26:09.225 [2426.2426] INFO SampleApp: shat down connection on UNIX socket Sep 02 17:26:09 hub systemd[1]: qobuz-connect.service: Succeeded. Sep 02 17:26:09 hub systemd[1]: Stopped Volumio Qobuz Connect Service. Sep 02 17:26:09 hub volumio[1000]: info: CoreCommandRouter::volumioGetState Sep 02 17:26:09 hub volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 02 17:26:09 hub volumio[1000]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Sep 02 17:26:09 hub volumio[1000]: info: MPD Permissions set Sep 02 17:26:09 hub volumio[1000]: info: Shairport-Sync Started Sep 02 17:26:09 hub volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 02 17:26:09 hub volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 02 17:26:09 hub volumio[1000]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Sep 02 17:26:09 hub volumio[1000]: info: QobuzConnect: setDeactiveState invoked Sep 02 17:26:09 hub volumio[1000]: info: CoreCommandRouter::volumioGetState Sep 02 17:26:09 hub volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 02 17:26:09 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 17:26:09 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 17:26:09 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 17:26:09 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 17:26:09 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 17:26:09 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 17:26:09 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 17:26:09 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 17:26:09 hub systemd[1]: Started Volumio Qobuz Connect Service. Sep 02 17:26:09 hub sudo[2471]: pam_unix(sudo:session): session closed for user root Sep 02 17:26:09 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 17:26:09 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 17:26:09 hub volumio[1000]: info: Starting Shairport Sync Sep 02 17:26:09 hub volumio[1000]: info: Asound.conf file unchanged, so no further update is needed Sep 02 17:26:09 hub volumio[1000]: info: Output device has changed, restarting MPD Sep 02 17:26:09 hub sudo[2482]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 02 17:26:09 hub sudo[2482]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 17:26:09 hub systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 02 17:26:09 hub sudo[2485]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 02 17:26:09 hub systemd[1]: shairport-sync.service: Succeeded. Sep 02 17:26:09 hub sudo[2485]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 17:26:09 hub systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 02 17:26:09 hub systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 02 17:26:09 hub sudo[2485]: pam_unix(sudo:session): session closed for user root Sep 02 17:26:09 hub sudo[2482]: pam_unix(sudo:session): session closed for user root Sep 02 17:26:09 hub volumio[1000]: info: Output device has changed, restarting Shairport Sync Sep 02 17:26:09 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 17:26:09 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 17:26:09 hub sudo[2489]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 02 17:26:09 hub sudo[2489]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 17:26:09 hub systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 02 17:26:09 hub systemd[1]: mpd.service: Succeeded. Sep 02 17:26:09 hub systemd[1]: Stopped Music Player Daemon. Sep 02 17:26:09 hub systemd[1]: Starting Music Player Daemon... Sep 02 17:26:09 hub volumio[1000]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 02 17:26:09 hub volumio[1000]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Sep 02 17:26:09 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 17:26:09 hub volumio[1000]: info: QobuzConnect: setDeactiveState invoked Sep 02 17:26:09 hub volumio[1000]: info: CoreCommandRouter::volumioGetState Sep 02 17:26:09 hub volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 02 17:26:09 hub volumio[1000]: info: Preparing to generate the ALSA configuration file Sep 02 17:26:09 hub sudo[2496]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 02 17:26:09 hub sudo[2500]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Sep 02 17:26:09 hub sudo[2496]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 17:26:09 hub sudo[2500]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 17:26:09 hub volumio[1000]: info: PLUGIN START: peppyspectrum Sep 02 17:26:09 hub sudo[2496]: pam_unix(sudo:session): session closed for user root Sep 02 17:26:09 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 02 17:26:09 hub volumio[1000]: info: Done. Sep 02 17:26:09 hub sudo[2505]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Sep 02 17:26:09 hub sudo[2505]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 17:26:09 hub sudo[2500]: pam_unix(sudo:session): session closed for user root Sep 02 17:26:09 hub sudo[2505]: pam_unix(sudo:session): session closed for user root Sep 02 17:26:09 hub sudo[2513]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Sep 02 17:26:09 hub volumio[1000]: info: Executing endpoint qc_getconfig Sep 02 17:26:09 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Sep 02 17:26:09 hub sudo[2513]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 17:26:09 hub qobuz-connect[2475]: 20250902 17:26:09.772 [2475.2475] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Sep 02 17:26:09 hub volumio[1000]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Sep 02 17:26:09 hub volumio[1000]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 02 17:26:09 hub volumio[1000]: info: Reading ALSA contributions from plugins. Sep 02 17:26:09 hub qobuz-connect[2475]: 20250902 17:26:09.781 [2475.2475] INFO VolumeManager: [0x24b7a70]: Setting new playback volume: 75 Sep 02 17:26:09 hub qobuz-connect[2475]: 20250902 17:26:09.781 [2475.2475] INFO VolumeManager: [0x24b7a70]: Setting new mute state: 0 Sep 02 17:26:09 hub qobuz-connect[2475]: 20250902 17:26:09.781 [2475.2475] INFO QobuzConnect: [0x24b8440]: Client initialized! Sep 02 17:26:09 hub qobuz-connect[2475]: 20250902 17:26:09.781 [2475.2475] INFO SampleApp: Starting Avahi advertising, name: Hub, service name: _qobuz-connect._tcp Sep 02 17:26:09 hub volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 02 17:26:09 hub volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 02 17:26:09 hub qobuz-connect[2475]: 20250902 17:26:09.803 [2475.2475] INFO LocalConfigManager: [0x24b7350]: Starting Local Configuration server Sep 02 17:26:09 hub qobuz-connect[2475]: 20250902 17:26:09.804 [2475.2475] INFO SampleApp: Starting Local configuration server Sep 02 17:26:09 hub qobuz-connect[2475]: 20250902 17:26:09.804 [2475.2475] INFO SampleApp: Connected to UNIX socket client 0x24acf60 Sep 02 17:26:09 hub volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 02 17:26:09 hub volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 02 17:26:09 hub sudo[2513]: pam_unix(sudo:session): session closed for user root Sep 02 17:26:09 hub volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 02 17:26:09 hub volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 02 17:26:09 hub sudo[2516]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Sep 02 17:26:09 hub sudo[2516]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 17:26:09 hub volumio[1000]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 02 17:26:09 hub volumio[1000]: info: Stopping peppyspectrum service Sep 02 17:26:09 hub volumio[1000]: info: CoreStateMachine::stop Sep 02 17:26:09 hub volumio[1000]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 02 17:26:09 hub systemd[1]: Stopping Volumio Qobuz Connect Service... Sep 02 17:26:09 hub qobuz-connect[2475]: 20250902 17:26:09.891 [2475.2475] INFO SampleApp: Stopping Local configuration server Sep 02 17:26:09 hub qobuz-connect[2475]: 20250902 17:26:09.901 [2475.2475] INFO SampleApp: shat down connection on UNIX socket Sep 02 17:26:09 hub systemd[1]: qobuz-connect.service: Succeeded. Sep 02 17:26:09 hub systemd[1]: Stopped Volumio Qobuz Connect Service. Sep 02 17:26:09 hub volumio[1000]: info: Disabling plugin peppyspectrum Sep 02 17:26:09 hub volumio[1000]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Sep 02 17:26:09 hub volumio[1000]: info: QobuzConnect: QOBUZ Connect daemon connected Sep 02 17:26:09 hub sudo[2520]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Sep 02 17:26:09 hub sudo[2520]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 17:26:09 hub systemd[1]: Started Volumio Qobuz Connect Service. Sep 02 17:26:09 hub volumio[1000]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Sep 02 17:26:09 hub volumio[1000]: info: MPD Permissions set Sep 02 17:26:09 hub volumio[1000]: info: Shairport-Sync Started Sep 02 17:26:09 hub volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 02 17:26:09 hub volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 02 17:26:09 hub volumio[1000]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Sep 02 17:26:09 hub volumio[1000]: info: QobuzConnect: setDeactiveState invoked Sep 02 17:26:09 hub volumio[1000]: info: CoreCommandRouter::volumioGetState Sep 02 17:26:09 hub volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 02 17:26:10 hub sudo[2516]: pam_unix(sudo:session): session closed for user root Sep 02 17:26:10 hub volumio[1000]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Sep 02 17:26:10 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 17:26:10 hub sudo[2520]: pam_unix(sudo:session): session closed for user root Sep 02 17:26:10 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 17:26:10 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 17:26:10 hub volumio[1000]: info: FusionDsp - no preset used Sep 02 17:26:10 hub volumio[1000]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json,Bass kinda?.json,Normal.json Sep 02 17:26:10 hub volumio[1000]: info: Executing endpoint qc_getconfig Sep 02 17:26:10 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Sep 02 17:26:10 hub qobuz-connect[2519]: 20250902 17:26:10.315 [2519.2519] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Sep 02 17:26:10 hub volumio[1000]: info: Starting Shairport Sync Sep 02 17:26:10 hub qobuz-connect[2519]: 20250902 17:26:10.319 [2519.2519] INFO VolumeManager: [0xedea70]: Setting new playback volume: 75 Sep 02 17:26:10 hub qobuz-connect[2519]: 20250902 17:26:10.319 [2519.2519] INFO VolumeManager: [0xedea70]: Setting new mute state: 0 Sep 02 17:26:10 hub qobuz-connect[2519]: 20250902 17:26:10.320 [2519.2519] INFO QobuzConnect: [0xedf440]: Client initialized! Sep 02 17:26:10 hub qobuz-connect[2519]: 20250902 17:26:10.320 [2519.2519] INFO SampleApp: Starting Avahi advertising, name: Hub, service name: _qobuz-connect._tcp Sep 02 17:26:10 hub qobuz-connect[2519]: 20250902 17:26:10.332 [2519.2519] INFO LocalConfigManager: [0xede350]: Starting Local Configuration server Sep 02 17:26:10 hub qobuz-connect[2519]: 20250902 17:26:10.332 [2519.2519] INFO SampleApp: Starting Local configuration server Sep 02 17:26:10 hub qobuz-connect[2519]: 20250902 17:26:10.333 [2519.2519] INFO SampleApp: Connected to UNIX socket client 0xed3f60 Sep 02 17:26:10 hub volumio[1000]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 02 17:26:10 hub sudo[2529]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 02 17:26:10 hub sudo[2529]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 17:26:10 hub volumio[1000]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Sep 02 17:26:10 hub volumio[1000]: info: QobuzConnect: QOBUZ Connect daemon connected Sep 02 17:26:10 hub systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 02 17:26:10 hub systemd[1]: shairport-sync.service: Succeeded. Sep 02 17:26:10 hub systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 02 17:26:10 hub volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 02 17:26:10 hub volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 02 17:26:10 hub volumio[1000]: info: Asound.conf file written Sep 02 17:26:10 hub systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 02 17:26:10 hub qobuz-connect[2519]: 20250902 17:26:10.469 [2519.2519] INFO SampleApp: Playback volume changed: 75 Sep 02 17:26:10 hub sudo[2533]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Sep 02 17:26:10 hub sudo[2533]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 17:26:10 hub sudo[2529]: pam_unix(sudo:session): session closed for user root Sep 02 17:26:10 hub sudo[2533]: pam_unix(sudo:session): session closed for user root Sep 02 17:26:10 hub volumio[1000]: info: Output device has changed, restarting MPD Sep 02 17:26:10 hub sudo[2540]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 02 17:26:10 hub sudo[2540]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 17:26:10 hub sudo[2540]: pam_unix(sudo:session): session closed for user root Sep 02 17:26:10 hub volumio[1000]: info: Output device has changed, restarting Shairport Sync Sep 02 17:26:10 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 17:26:10 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 17:26:10 hub sudo[2543]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 02 17:26:10 hub sudo[2543]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 17:26:10 hub systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 02 17:26:10 hub systemd[1]: mpd.service: Succeeded. Sep 02 17:26:10 hub systemd[1]: Stopped Music Player Daemon. Sep 02 17:26:10 hub volumio[1000]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 02 17:26:10 hub volumio[1000]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Sep 02 17:26:10 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 17:26:10 hub volumio[1000]: info: QobuzConnect: setDeactiveState invoked Sep 02 17:26:10 hub volumio[1000]: info: CoreCommandRouter::volumioGetState Sep 02 17:26:10 hub volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 02 17:26:10 hub volumio[1000]: info: Preparing to generate the ALSA configuration file Sep 02 17:26:10 hub sudo[2552]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Sep 02 17:26:10 hub sudo[2552]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 17:26:10 hub systemd[1]: Starting Music Player Daemon... Sep 02 17:26:10 hub volumio[1000]: info: Done. Sep 02 17:26:10 hub sudo[2555]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Sep 02 17:26:10 hub sudo[2555]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 17:26:10 hub sudo[2552]: pam_unix(sudo:session): session closed for user root Sep 02 17:26:10 hub sudo[2555]: pam_unix(sudo:session): session closed for user root Sep 02 17:26:10 hub sudo[2548]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 02 17:26:10 hub sudo[2548]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 17:26:10 hub sudo[2563]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Sep 02 17:26:10 hub sudo[2548]: pam_unix(sudo:session): session closed for user root Sep 02 17:26:10 hub sudo[2563]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 17:26:10 hub volumio[1000]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Sep 02 17:26:10 hub volumio[1000]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 02 17:26:10 hub volumio[1000]: info: Reading ALSA contributions from plugins. Sep 02 17:26:10 hub sudo[2563]: pam_unix(sudo:session): session closed for user root Sep 02 17:26:10 hub volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 02 17:26:10 hub volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 02 17:26:10 hub volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 02 17:26:10 hub volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 02 17:26:10 hub sudo[2567]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Sep 02 17:26:10 hub sudo[2567]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 17:26:10 hub volumio[1000]: info: CoreCommandRouter::volumioGetState Sep 02 17:26:10 hub volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 02 17:26:10 hub systemd[1]: Stopping Volumio Qobuz Connect Service... Sep 02 17:26:10 hub qobuz-connect[2519]: 20250902 17:26:10.916 [2519.2519] INFO SampleApp: Stopping Local configuration server Sep 02 17:26:10 hub qobuz-connect[2519]: 20250902 17:26:10.929 [2519.2519] INFO SampleApp: shat down connection on UNIX socket Sep 02 17:26:10 hub systemd[1]: qobuz-connect.service: Succeeded. Sep 02 17:26:10 hub systemd[1]: Stopped Volumio Qobuz Connect Service. Sep 02 17:26:10 hub volumio[1000]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Sep 02 17:26:10 hub volumio[1000]: info: MPD Permissions set Sep 02 17:26:10 hub volumio[1000]: info: Shairport-Sync Started Sep 02 17:26:10 hub volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 02 17:26:10 hub volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 02 17:26:10 hub volumio[1000]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Sep 02 17:26:10 hub volumio[1000]: info: QobuzConnect: setDeactiveState invoked Sep 02 17:26:10 hub volumio[1000]: info: CoreCommandRouter::volumioGetState Sep 02 17:26:10 hub volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 02 17:26:11 hub volumio[1000]: info: PeppySpectrum ---list is Random,black-balbuze99,white-balbuze99,large-white-rb-balbuze99,large-black-gr-balbuze99,large-black-br-balbuze99,large-black-br-r-balbuze99,large-black-gr-r-balbuze99,large-black-gr-rs-balbuze99,large-white-gr-r-balbuze99 Sep 02 17:26:11 hub volumio[1000]: info: PeppySpectrum ---1280x400+30-30bars-balbuze Sep 02 17:26:11 hub volumio[1000]: info: PeppySpectrum ---1280x400+30-custom Sep 02 17:26:11 hub volumio[1000]: info: PeppySpectrum ---1280x400+30-Gelo5 Sep 02 17:26:11 hub volumio[1000]: info: PeppySpectrum ---1280x400+99-99bars-balbuze Sep 02 17:26:11 hub volumio[1000]: info: PeppySpectrum ---1480x320+99-99bars-balbuze Sep 02 17:26:11 hub volumio[1000]: info: PeppySpectrum ---1920x1080+13-Gelo5 Sep 02 17:26:11 hub volumio[1000]: info: PeppySpectrum ---1920x1080+30-Gelo5 Sep 02 17:26:11 hub volumio[1000]: info: PeppySpectrum ---1920x1080+55-Gelo5 Sep 02 17:26:11 hub volumio[1000]: info: PeppySpectrum ---1920x720+99-99bars-balbuze Sep 02 17:26:11 hub volumio[1000]: info: PeppySpectrum ---800x100+99-99bars Sep 02 17:26:11 hub volumio[1000]: info: PeppySpectrum ---800x46+99-99bars-balbuze Sep 02 17:26:11 hub volumio[1000]: info: PeppySpectrum ---800x480+20-marshall Sep 02 17:26:11 hub volumio[1000]: info: PeppySpectrum ---800x480+230-230-bars-freq Sep 02 17:26:11 hub volumio[1000]: info: PeppySpectrum ---800x480+30-30bars-balbuze Sep 02 17:26:11 hub volumio[1000]: info: PeppySpectrum ---800x480+30-Gelo5 Sep 02 17:26:11 hub volumio[1000]: info: PeppySpectrum ---800x480+30-marshall Sep 02 17:26:11 hub volumio[1000]: info: PeppySpectrum ---800x480+55-marshall Sep 02 17:26:11 hub volumio[1000]: info: PeppySpectrum ---800x480+99-99bars-balbuze Sep 02 17:26:11 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 17:26:11 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 17:26:11 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 17:26:11 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 17:26:11 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 17:26:11 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 17:26:11 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 17:26:11 hub systemd[1]: Started Volumio Qobuz Connect Service. Sep 02 17:26:11 hub sudo[2567]: pam_unix(sudo:session): session closed for user root Sep 02 17:26:11 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 17:26:11 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 17:26:11 hub volumio[1000]: info: TidalConnect service stoped! Sep 02 17:26:11 hub volumio[1000]: info: Executing endpoint qc_getconfig Sep 02 17:26:11 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Sep 02 17:26:11 hub qobuz-connect[2569]: 20250902 17:26:11.170 [2569.2569] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Sep 02 17:26:11 hub volumio[1000]: info: Starting Shairport Sync Sep 02 17:26:11 hub qobuz-connect[2569]: 20250902 17:26:11.174 [2569.2569] INFO VolumeManager: [0x5d1a70]: Setting new playback volume: 75 Sep 02 17:26:11 hub qobuz-connect[2569]: 20250902 17:26:11.174 [2569.2569] INFO VolumeManager: [0x5d1a70]: Setting new mute state: 0 Sep 02 17:26:11 hub qobuz-connect[2569]: 20250902 17:26:11.174 [2569.2569] INFO QobuzConnect: [0x5d2440]: Client initialized! Sep 02 17:26:11 hub qobuz-connect[2569]: 20250902 17:26:11.174 [2569.2569] INFO SampleApp: Starting Avahi advertising, name: Hub, service name: _qobuz-connect._tcp Sep 02 17:26:11 hub qobuz-connect[2569]: 20250902 17:26:11.183 [2569.2569] INFO LocalConfigManager: [0x5d1350]: Starting Local Configuration server Sep 02 17:26:11 hub qobuz-connect[2569]: 20250902 17:26:11.183 [2569.2569] INFO SampleApp: Starting Local configuration server Sep 02 17:26:11 hub qobuz-connect[2569]: 20250902 17:26:11.184 [2569.2569] INFO SampleApp: Connected to UNIX socket client 0x5c6f60 Sep 02 17:26:11 hub volumio[1000]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Sep 02 17:26:11 hub volumio[1000]: info: QobuzConnect: QOBUZ Connect daemon connected Sep 02 17:26:11 hub volumio[1000]: info: TidalConnect service stoped! Sep 02 17:26:11 hub sudo[2577]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 02 17:26:11 hub sudo[2577]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 17:26:11 hub systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 02 17:26:11 hub systemd[1]: shairport-sync.service: Succeeded. Sep 02 17:26:11 hub systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 02 17:26:11 hub volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 02 17:26:11 hub volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 02 17:26:11 hub qobuz-connect[2569]: 20250902 17:26:11.320 [2569.2569] INFO SampleApp: Playback volume changed: 75 Sep 02 17:26:11 hub volumio[1000]: info: Asound.conf file unchanged, so no further update is needed Sep 02 17:26:11 hub volumio[1000]: info: Output device has changed, restarting MPD Sep 02 17:26:11 hub systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 02 17:26:11 hub sudo[2577]: pam_unix(sudo:session): session closed for user root Sep 02 17:26:11 hub sudo[2589]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 02 17:26:11 hub sudo[2589]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 17:26:11 hub sudo[2589]: pam_unix(sudo:session): session closed for user root Sep 02 17:26:11 hub volumio[1000]: info: Output device has changed, restarting Shairport Sync Sep 02 17:26:11 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 17:26:11 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 17:26:11 hub sudo[2593]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 02 17:26:11 hub sudo[2593]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 17:26:11 hub systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 02 17:26:11 hub systemd[1]: mpd.service: Succeeded. Sep 02 17:26:11 hub systemd[1]: Stopped Music Player Daemon. Sep 02 17:26:11 hub volumio[1000]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 02 17:26:11 hub volumio[1000]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Sep 02 17:26:11 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 17:26:11 hub volumio[1000]: info: QobuzConnect: setDeactiveState invoked Sep 02 17:26:11 hub volumio[1000]: info: CoreCommandRouter::volumioGetState Sep 02 17:26:11 hub volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 02 17:26:11 hub systemd[1]: Starting Music Player Daemon... Sep 02 17:26:11 hub volumio[1000]: info: Preparing to generate the ALSA configuration file Sep 02 17:26:11 hub sudo[2603]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Sep 02 17:26:11 hub sudo[2603]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 17:26:11 hub sudo[2603]: pam_unix(sudo:session): session closed for user root Sep 02 17:26:11 hub sudo[2599]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 02 17:26:11 hub sudo[2599]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 17:26:11 hub sudo[2599]: pam_unix(sudo:session): session closed for user root Sep 02 17:26:11 hub sudo[2612]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Sep 02 17:26:11 hub sudo[2612]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 17:26:11 hub sudo[2612]: pam_unix(sudo:session): session closed for user root Sep 02 17:26:11 hub volumio[1000]: info: CoreCommandRouter::volumioGetState Sep 02 17:26:11 hub volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 02 17:26:11 hub volumio[1000]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Sep 02 17:26:11 hub volumio[1000]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 02 17:26:11 hub volumio[1000]: info: Reading ALSA contributions from plugins. Sep 02 17:26:11 hub volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 02 17:26:11 hub volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 02 17:26:11 hub volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 02 17:26:11 hub sudo[2619]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Sep 02 17:26:11 hub sudo[2619]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 17:26:11 hub volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 02 17:26:11 hub volumio[1000]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Sep 02 17:26:11 hub volumio[1000]: info: MPD Permissions set Sep 02 17:26:11 hub volumio[1000]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Sep 02 17:26:11 hub volumio[1000]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Sep 02 17:26:11 hub volumio[1000]: info: Shairport-Sync Started Sep 02 17:26:11 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 17:26:11 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 17:26:11 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 17:26:11 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 17:26:11 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 17:26:11 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 17:26:11 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 17:26:11 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 17:26:11 hub sudo[2619]: pam_unix(sudo:session): session closed for user root Sep 02 17:26:11 hub sudo[2621]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Sep 02 17:26:11 hub sudo[2621]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 17:26:11 hub systemd[1]: Stopping Volumio Qobuz Connect Service... Sep 02 17:26:11 hub qobuz-connect[2569]: 20250902 17:26:11.712 [2569.2569] INFO SampleApp: Stopping Local configuration server Sep 02 17:26:11 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 17:26:11 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 17:26:11 hub qobuz-connect[2569]: 20250902 17:26:11.722 [2569.2569] INFO SampleApp: shat down connection on UNIX socket Sep 02 17:26:11 hub systemd[1]: qobuz-connect.service: Succeeded. Sep 02 17:26:11 hub systemd[1]: Stopped Volumio Qobuz Connect Service. Sep 02 17:26:11 hub volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 02 17:26:11 hub volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 02 17:26:11 hub volumio[1000]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Sep 02 17:26:11 hub volumio[1000]: info: QobuzConnect: setDeactiveState invoked Sep 02 17:26:11 hub volumio[1000]: info: CoreCommandRouter::volumioGetState Sep 02 17:26:11 hub volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 02 17:26:11 hub volumio[1000]: info: Starting Shairport Sync Sep 02 17:26:11 hub sudo[2629]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Sep 02 17:26:11 hub sudo[2629]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 17:26:11 hub systemd[1]: Started Volumio Qobuz Connect Service. Sep 02 17:26:11 hub sudo[2621]: pam_unix(sudo:session): session closed for user root Sep 02 17:26:11 hub systemd[1]: Started Volumio Tidal Connect Service. Sep 02 17:26:11 hub sudo[2629]: pam_unix(sudo:session): session closed for user root Sep 02 17:26:11 hub volumio[1000]: info: Asound.conf file unchanged, so no further update is needed Sep 02 17:26:11 hub volumio[1000]: info: Output device has changed, restarting MPD Sep 02 17:26:11 hub sudo[2634]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 02 17:26:11 hub sudo[2634]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 17:26:11 hub sudo[2639]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 02 17:26:11 hub sudo[2639]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 17:26:11 hub systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 02 17:26:11 hub systemd[1]: shairport-sync.service: Succeeded. Sep 02 17:26:11 hub systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 02 17:26:11 hub systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 02 17:26:11 hub volumio[1000]: info: Output device has changed, restarting Shairport Sync Sep 02 17:26:11 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 17:26:11 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 17:26:11 hub sudo[2634]: pam_unix(sudo:session): session closed for user root Sep 02 17:26:11 hub sudo[2643]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 02 17:26:11 hub sudo[2643]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 17:26:11 hub sudo[2639]: pam_unix(sudo:session): session closed for user root Sep 02 17:26:11 hub systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 02 17:26:11 hub systemd[1]: mpd.service: Succeeded. Sep 02 17:26:11 hub systemd[1]: Stopped Music Player Daemon. Sep 02 17:26:11 hub systemd[1]: Starting Music Player Daemon... Sep 02 17:26:12 hub volumio[1000]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 02 17:26:12 hub volumio[1000]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Sep 02 17:26:12 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 17:26:12 hub volumio[1000]: info: QobuzConnect: setDeactiveState invoked Sep 02 17:26:12 hub volumio[1000]: info: CoreCommandRouter::volumioGetState Sep 02 17:26:12 hub volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 02 17:26:12 hub volumio[1000]: info: Done. Sep 02 17:26:12 hub sudo[2650]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 02 17:26:12 hub sudo[2650]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 17:26:12 hub sudo[2654]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Sep 02 17:26:12 hub sudo[2654]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 17:26:12 hub sudo[2650]: pam_unix(sudo:session): session closed for user root Sep 02 17:26:12 hub systemd[1]: Stopping Volumio Tidal Connect Service... Sep 02 17:26:12 hub systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Sep 02 17:26:12 hub systemd[1]: vtcs.service: Succeeded. Sep 02 17:26:12 hub systemd[1]: Stopped Volumio Tidal Connect Service. Sep 02 17:26:12 hub sudo[2660]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Sep 02 17:26:12 hub sudo[2660]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 17:26:12 hub sudo[2654]: pam_unix(sudo:session): session closed for user root Sep 02 17:26:12 hub sudo[2660]: pam_unix(sudo:session): session closed for user root Sep 02 17:26:12 hub volumio[1000]: info: Executing endpoint qc_getconfig Sep 02 17:26:12 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Sep 02 17:26:12 hub qobuz-connect[2628]: 20250902 17:26:12.173 [2628.2628] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Sep 02 17:26:12 hub qobuz-connect[2628]: 20250902 17:26:12.177 [2628.2628] INFO VolumeManager: [0x9eda70]: Setting new playback volume: 75 Sep 02 17:26:12 hub qobuz-connect[2628]: 20250902 17:26:12.178 [2628.2628] INFO VolumeManager: [0x9eda70]: Setting new mute state: 0 Sep 02 17:26:12 hub qobuz-connect[2628]: 20250902 17:26:12.178 [2628.2628] INFO QobuzConnect: [0x9ee440]: Client initialized! Sep 02 17:26:12 hub qobuz-connect[2628]: 20250902 17:26:12.178 [2628.2628] INFO SampleApp: Starting Avahi advertising, name: Hub, service name: _qobuz-connect._tcp Sep 02 17:26:12 hub sudo[2668]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Sep 02 17:26:12 hub sudo[2668]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 17:26:12 hub volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 02 17:26:12 hub volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 02 17:26:12 hub volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 02 17:26:12 hub volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 02 17:26:12 hub volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 02 17:26:12 hub volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 02 17:26:12 hub volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 02 17:26:12 hub qobuz-connect[2628]: 20250902 17:26:12.197 [2628.2628] INFO LocalConfigManager: [0x9ed350]: Starting Local Configuration server Sep 02 17:26:12 hub qobuz-connect[2628]: 20250902 17:26:12.197 [2628.2628] INFO SampleApp: Starting Local configuration server Sep 02 17:26:12 hub qobuz-connect[2628]: 20250902 17:26:12.198 [2628.2628] INFO SampleApp: Connected to UNIX socket client 0x9e2f60 Sep 02 17:26:12 hub volumio[1000]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Sep 02 17:26:12 hub volumio[1000]: info: QobuzConnect: QOBUZ Connect daemon connected Sep 02 17:26:12 hub volumio[1000]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Sep 02 17:26:12 hub volumio[1000]: info: MPD Permissions set Sep 02 17:26:12 hub volumio[1000]: info: TidalConnect service stoped! Sep 02 17:26:12 hub sudo[2668]: pam_unix(sudo:session): session closed for user root Sep 02 17:26:12 hub sudo[2671]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Sep 02 17:26:12 hub sudo[2671]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 17:26:12 hub volumio[1000]: info: TidalConnect service stoped! Sep 02 17:26:12 hub volumio[1000]: info: Shairport-Sync Started Sep 02 17:26:12 hub systemd[1]: Stopping Volumio Qobuz Connect Service... Sep 02 17:26:12 hub qobuz-connect[2628]: 20250902 17:26:12.271 [2628.2628] INFO SampleApp: Stopping Local configuration server Sep 02 17:26:12 hub volumio[1000]: info: Executing endpoint tc_getconfig Sep 02 17:26:12 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Sep 02 17:26:12 hub qobuz-connect[2628]: 20250902 17:26:12.282 [2628.2628] INFO SampleApp: shat down connection on UNIX socket Sep 02 17:26:12 hub systemd[1]: qobuz-connect.service: Succeeded. Sep 02 17:26:12 hub systemd[1]: Stopped Volumio Qobuz Connect Service. Sep 02 17:26:12 hub volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 02 17:26:12 hub volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 02 17:26:12 hub volumio[1000]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Sep 02 17:26:12 hub volumio[1000]: info: QobuzConnect: setDeactiveState invoked Sep 02 17:26:12 hub volumio[1000]: info: CoreCommandRouter::volumioGetState Sep 02 17:26:12 hub volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 02 17:26:12 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 17:26:12 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 17:26:12 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 17:26:12 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 17:26:12 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 17:26:12 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 17:26:12 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 17:26:12 hub sudo[2671]: pam_unix(sudo:session): session closed for user root Sep 02 17:26:12 hub systemd[1]: Started Volumio Qobuz Connect Service. Sep 02 17:26:12 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 17:26:12 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 17:26:12 hub volumio[1000]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Sep 02 17:26:12 hub volumio[1000]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Sep 02 17:26:12 hub volumio[1000]: info: Executing endpoint qc_getconfig Sep 02 17:26:12 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Sep 02 17:26:12 hub qobuz-connect[2677]: 20250902 17:26:12.387 [2677.2677] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Sep 02 17:26:12 hub volumio[1000]: info: Starting Shairport Sync Sep 02 17:26:12 hub qobuz-connect[2677]: 20250902 17:26:12.392 [2677.2677] INFO VolumeManager: [0x1e09a70]: Setting new playback volume: 75 Sep 02 17:26:12 hub qobuz-connect[2677]: 20250902 17:26:12.392 [2677.2677] INFO VolumeManager: [0x1e09a70]: Setting new mute state: 0 Sep 02 17:26:12 hub qobuz-connect[2677]: 20250902 17:26:12.392 [2677.2677] INFO QobuzConnect: [0x1e0a440]: Client initialized! Sep 02 17:26:12 hub qobuz-connect[2677]: 20250902 17:26:12.392 [2677.2677] INFO SampleApp: Starting Avahi advertising, name: Hub, service name: _qobuz-connect._tcp Sep 02 17:26:12 hub qobuz-connect[2677]: 20250902 17:26:12.402 [2677.2677] INFO LocalConfigManager: [0x1e09350]: Starting Local Configuration server Sep 02 17:26:12 hub qobuz-connect[2677]: 20250902 17:26:12.402 [2677.2677] INFO SampleApp: Starting Local configuration server Sep 02 17:26:12 hub qobuz-connect[2677]: 20250902 17:26:12.402 [2677.2677] INFO SampleApp: Connected to UNIX socket client 0x1dfef60 Sep 02 17:26:12 hub sudo[2689]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 02 17:26:12 hub sudo[2689]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 17:26:12 hub volumio[1000]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Sep 02 17:26:12 hub volumio[1000]: info: QobuzConnect: QOBUZ Connect daemon connected Sep 02 17:26:12 hub systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 02 17:26:12 hub systemd[1]: shairport-sync.service: Succeeded. Sep 02 17:26:12 hub volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 02 17:26:12 hub systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 02 17:26:12 hub sudo[2692]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Sep 02 17:26:12 hub systemd[1]: shairport-sync.service: Start request repeated too quickly. Sep 02 17:26:12 hub systemd[1]: shairport-sync.service: Failed with result 'start-limit-hit'. Sep 02 17:26:12 hub volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 02 17:26:12 hub sudo[2692]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 17:26:12 hub systemd[1]: Failed to start Shairport Sync - AirPlay Audio Receiver. Sep 02 17:26:12 hub sudo[2689]: pam_unix(sudo:session): session closed for user root Sep 02 17:26:12 hub volumio[1000]: info: Shairport-sync error: Error: Command failed: /usr/bin/sudo /bin/systemctl restart shairport-sync Sep 02 17:26:12 hub volumio[1000]: Job for shairport-sync.service failed. Sep 02 17:26:12 hub volumio[1000]: See "systemctl status shairport-sync.service" and "journalctl -xe" for details. Sep 02 17:26:12 hub qobuz-connect[2677]: 20250902 17:26:12.537 [2677.2677] INFO SampleApp: Playback volume changed: 75 Sep 02 17:26:12 hub volumio[1000]: info: CoreCommandRouter::volumioGetState Sep 02 17:26:12 hub volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 02 17:26:12 hub systemd[1]: Started Volumio Tidal Connect Service. Sep 02 17:26:12 hub sudo[2692]: pam_unix(sudo:session): session closed for user root Sep 02 17:26:12 hub volumio[1000]: info: TidalConnect service stoped! Sep 02 17:26:12 hub volumio[1000]: info: Executing endpoint tc_getconfig Sep 02 17:26:12 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Sep 02 17:26:12 hub volumio[1000]: info: TidalConnect service stoped! Sep 02 17:26:12 hub vtcs[2694]: STARTING TidalConnect services, version: 1.5.1-beta1 Sep 02 17:26:12 hub vtcs[2694]: STARTED TidalConnect services. Sep 02 17:26:12 hub volumio[1000]: info: Executing endpoint tc_connect Sep 02 17:26:12 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Sep 02 17:26:12 hub volumio[1000]: info: Connecting to TidalConnect Sep 02 17:26:12 hub volumio[1000]: info: CoreCommandRouter::servicePushState Sep 02 17:26:12 hub volumio[1000]: info: CoreStateMachine::pushState Sep 02 17:26:12 hub volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 02 17:26:12 hub volumio[1000]: info: CoreCommandRouter::volumioPushState Sep 02 17:26:12 hub volumio[1000]: info: CoreCommandRouter::volumioGetState Sep 02 17:26:12 hub volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 02 17:26:12 hub volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 02 17:26:12 hub volumio[1000]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Sep 02 17:26:12 hub volumio[1000]: verbose: CURRENT POSITION 0 Sep 02 17:26:12 hub volumio[1000]: info: CoreStateMachine::syncState stateService stop Sep 02 17:26:12 hub volumio[1000]: info: CoreStateMachine::syncState currentStatus stop Sep 02 17:26:12 hub volumio[1000]: info: CoreStateMachine::pushState Sep 02 17:26:12 hub volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 02 17:26:12 hub volumio[1000]: info: CoreCommandRouter::volumioPushState Sep 02 17:26:12 hub volumio[1000]: info: CoreCommandRouter::volumioGetState Sep 02 17:26:12 hub volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 02 17:26:12 hub volumio[1000]: info: No code Sep 02 17:26:12 hub volumio[1000]: info: CoreStateMachine::pushState Sep 02 17:26:12 hub volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 02 17:26:12 hub volumio[1000]: info: CoreCommandRouter::volumioPushState Sep 02 17:26:12 hub volumio[1000]: info: CoreCommandRouter::volumioGetState Sep 02 17:26:12 hub volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 02 17:26:12 hub volumio[1000]: info: CoreCommandRouter::servicePushState Sep 02 17:26:12 hub volumio[1000]: info: CoreStateMachine::pushState Sep 02 17:26:12 hub volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 02 17:26:12 hub volumio[1000]: info: CoreCommandRouter::volumioPushState Sep 02 17:26:12 hub volumio[1000]: info: CoreCommandRouter::volumioGetState Sep 02 17:26:12 hub volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 02 17:26:12 hub volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 02 17:26:12 hub volumio[1000]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Sep 02 17:26:12 hub volumio[1000]: verbose: CURRENT POSITION 0 Sep 02 17:26:12 hub volumio[1000]: info: CoreStateMachine::syncState stateService stop Sep 02 17:26:12 hub volumio[1000]: info: CoreStateMachine::syncState currentStatus stop Sep 02 17:26:12 hub volumio[1000]: info: CoreStateMachine::pushState Sep 02 17:26:12 hub volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 02 17:26:12 hub volumio[1000]: info: CoreCommandRouter::volumioPushState Sep 02 17:26:12 hub volumio[1000]: info: CoreCommandRouter::volumioGetState Sep 02 17:26:12 hub volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 02 17:26:12 hub volumio[1000]: info: No code Sep 02 17:26:12 hub volumio[1000]: info: CoreStateMachine::pushState Sep 02 17:26:12 hub volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 02 17:26:12 hub volumio[1000]: info: CoreCommandRouter::volumioPushState Sep 02 17:26:12 hub volumio[1000]: info: CoreCommandRouter::volumioGetState Sep 02 17:26:12 hub volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 02 17:26:12 hub volumio[1000]: info: [ASDebug] CurState: stop PrevState: stop Sep 02 17:26:12 hub volumio[1000]: info: [ASDebug] CurState: stop PrevState: stop Sep 02 17:26:12 hub volumio[1000]: info: [ASDebug] CurState: stop PrevState: stop Sep 02 17:26:12 hub volumio[1000]: info: [ASDebug] CurState: stop PrevState: stop Sep 02 17:26:12 hub volumio[1000]: info: [ASDebug] CurState: stop PrevState: stop Sep 02 17:26:12 hub volumio[1000]: info: [ASDebug] CurState: stop PrevState: stop Sep 02 17:26:12 hub volumio[1000]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Sep 02 17:26:12 hub volumio[1000]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Sep 02 17:26:12 hub volumio[1000]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Sep 02 17:26:12 hub volumio[1000]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Sep 02 17:26:12 hub volumio[1000]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Sep 02 17:26:12 hub volumio[1000]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Sep 02 17:26:12 hub mpd[2659]: Sep 02 17:26 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 02 17:26:12 hub systemd[1]: Started Music Player Daemon. Sep 02 17:26:12 hub sudo[2543]: pam_unix(sudo:session): session closed for user root Sep 02 17:26:12 hub sudo[2643]: pam_unix(sudo:session): session closed for user root Sep 02 17:26:12 hub sudo[2400]: pam_unix(sudo:session): session closed for user root Sep 02 17:26:12 hub sudo[2593]: pam_unix(sudo:session): session closed for user root Sep 02 17:26:12 hub sudo[2489]: pam_unix(sudo:session): session closed for user root Sep 02 17:26:12 hub sudo[2441]: pam_unix(sudo:session): session closed for user root Sep 02 17:26:12 hub volumio[1000]: info: PeppySpectrum ---peppyspectrum status stop Sep 02 17:26:13 hub volumio[1000]: info: PeppySpectrum ---peppyspectrum status stop Sep 02 17:26:13 hub sudo[2725]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Sep 02 17:26:13 hub sudo[2725]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 17:26:13 hub volumio[1000]: info: PeppySpectrum ---peppyspectrum status stop Sep 02 17:26:13 hub sudo[2725]: pam_unix(sudo:session): session closed for user root Sep 02 17:26:13 hub sudo[2728]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Sep 02 17:26:13 hub sudo[2728]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 17:26:13 hub volumio[1000]: info: PeppySpectrum ---peppyspectrum status stop Sep 02 17:26:13 hub sudo[2731]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Sep 02 17:26:13 hub sudo[2728]: pam_unix(sudo:session): session closed for user root Sep 02 17:26:13 hub sudo[2731]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 17:26:13 hub volumio[1000]: info: PeppySpectrum ---peppyspectrum status stop Sep 02 17:26:13 hub sudo[2734]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Sep 02 17:26:13 hub sudo[2734]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 17:26:13 hub volumio[1000]: info: PeppySpectrum ---peppyspectrum status stop Sep 02 17:26:13 hub sudo[2731]: pam_unix(sudo:session): session closed for user root Sep 02 17:26:13 hub sudo[2737]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Sep 02 17:26:13 hub sudo[2737]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 17:26:13 hub sudo[2734]: pam_unix(sudo:session): session closed for user root Sep 02 17:26:13 hub volumio[1000]: info: PeppySpectrum ---peppyspectrum status stop Sep 02 17:26:13 hub sudo[2740]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Sep 02 17:26:13 hub sudo[2740]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 17:26:13 hub sudo[2737]: pam_unix(sudo:session): session closed for user root Sep 02 17:26:13 hub volumio[1000]: info: PeppySpectrum ---peppyspectrum status stop Sep 02 17:26:13 hub sudo[2743]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Sep 02 17:26:13 hub sudo[2743]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 17:26:13 hub sudo[2740]: pam_unix(sudo:session): session closed for user root Sep 02 17:26:13 hub volumio[1000]: info: PeppySpectrum ---peppyspectrum status stop Sep 02 17:26:13 hub sudo[2743]: pam_unix(sudo:session): session closed for user root Sep 02 17:26:13 hub sudo[2746]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Sep 02 17:26:13 hub volumio[1000]: info: PeppySpectrum ---peppyspectrum status stop Sep 02 17:26:13 hub sudo[2746]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 17:26:13 hub sudo[2748]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Sep 02 17:26:13 hub sudo[2748]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 17:26:13 hub volumio[1000]: info: PeppySpectrum ---peppyspectrum status stop Sep 02 17:26:13 hub sudo[2746]: pam_unix(sudo:session): session closed for user root Sep 02 17:26:13 hub sudo[2751]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Sep 02 17:26:13 hub sudo[2751]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 17:26:13 hub sudo[2748]: pam_unix(sudo:session): session closed for user root Sep 02 17:26:13 hub volumio[1000]: info: PeppySpectrum ---peppyspectrum status stop Sep 02 17:26:13 hub sudo[2751]: pam_unix(sudo:session): session closed for user root Sep 02 17:26:13 hub sudo[2755]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Sep 02 17:26:13 hub sudo[2755]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 17:26:13 hub volumio[1000]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Sep 02 17:26:13 hub volumio[1000]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Sep 02 17:26:13 hub sudo[2755]: pam_unix(sudo:session): session closed for user root Sep 02 17:26:13 hub sudo[2758]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Sep 02 17:26:13 hub sudo[2758]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 17:26:13 hub sudo[2758]: pam_unix(sudo:session): session closed for user root Sep 02 17:26:13 hub volumio[1000]: info: peppyspectrum Daemon Stop Sep 02 17:26:13 hub volumio[1000]: info: peppyspectrum Daemon Stop Sep 02 17:26:13 hub volumio[1000]: info: peppyspectrum Daemon Stop Sep 02 17:26:13 hub volumio[1000]: info: peppyspectrum Daemon Stop Sep 02 17:26:13 hub volumio[1000]: info: peppyspectrum Daemon Stop Sep 02 17:26:13 hub volumio[1000]: info: peppyspectrum Daemon Stop Sep 02 17:26:13 hub volumio[1000]: info: peppyspectrum Daemon Stop Sep 02 17:26:13 hub volumio[1000]: info: peppyspectrum Daemon Stop Sep 02 17:26:13 hub volumio[1000]: info: peppyspectrum Daemon Stop Sep 02 17:26:13 hub volumio[1000]: info: peppyspectrum Daemon Stop Sep 02 17:26:13 hub volumio[1000]: info: peppyspectrum Daemon Stop Sep 02 17:26:13 hub volumio[1000]: error: MPD error: The expression evaluated to a falsy value: Sep 02 17:26:13 hub volumio[1000]: assert.ok(self.idling) Sep 02 17:26:13 hub volumio[1000]: error: The expression evaluated to a falsy value: Sep 02 17:26:13 hub volumio[1000]: assert.ok(self.idling) Sep 02 17:26:13 hub volumio[1000]: error: MPD error: The expression evaluated to a falsy value: Sep 02 17:26:13 hub volumio[1000]: assert.ok(self.idling) Sep 02 17:26:13 hub volumio[1000]: error: The expression evaluated to a falsy value: Sep 02 17:26:13 hub volumio[1000]: assert.ok(self.idling) Sep 02 17:26:13 hub volumio[1000]: error: MPD error: The expression evaluated to a falsy value: Sep 02 17:26:13 hub volumio[1000]: assert.ok(self.idling) Sep 02 17:26:13 hub volumio[1000]: error: The expression evaluated to a falsy value: Sep 02 17:26:13 hub volumio[1000]: assert.ok(self.idling) Sep 02 17:26:13 hub volumio[1000]: error: MPD error: The expression evaluated to a falsy value: Sep 02 17:26:13 hub volumio[1000]: assert.ok(self.idling) Sep 02 17:26:13 hub volumio[1000]: error: The expression evaluated to a falsy value: Sep 02 17:26:13 hub volumio[1000]: assert.ok(self.idling) Sep 02 17:26:13 hub volumio[1000]: error: MPD error: The expression evaluated to a falsy value: Sep 02 17:26:13 hub volumio[1000]: assert.ok(self.idling) Sep 02 17:26:13 hub volumio[1000]: error: The expression evaluated to a falsy value: Sep 02 17:26:13 hub volumio[1000]: assert.ok(self.idling) Sep 02 17:26:13 hub volumio[1000]: info: TidalConnect service stoped! Sep 02 17:26:13 hub volumio[1000]: info: TidalConnect service stoped! Sep 02 17:26:13 hub volumio[1000]: error: updateQueue error: null Sep 02 17:26:13 hub sudo[2765]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Sep 02 17:26:13 hub sudo[2765]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 17:26:13 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 02 17:26:13 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Sep 02 17:26:13 hub sudo[2765]: pam_unix(sudo:session): session closed for user root Sep 02 17:26:14 hub volumio[1000]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Sep 02 17:26:14 hub volumio[1000]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Sep 02 17:26:14 hub sudo[2773]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Sep 02 17:26:14 hub sudo[2773]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 17:26:14 hub sudo[2773]: pam_unix(sudo:session): session closed for user root Sep 02 17:26:14 hub volumio[1000]: info: TidalConnect service stoped! Sep 02 17:26:14 hub volumio[1000]: info: TidalConnect service stoped! Sep 02 17:26:14 hub volumio[1000]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Sep 02 17:26:14 hub volumio[1000]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Sep 02 17:26:14 hub sudo[2799]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Sep 02 17:26:14 hub sudo[2799]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 17:26:14 hub sudo[2799]: pam_unix(sudo:session): session closed for user root Sep 02 17:26:14 hub volumio[1000]: info: TidalConnect service started! Sep 02 17:26:15 hub volumio[1000]: info: TidalConnect service stoped! Sep 02 17:26:15 hub volumio[1000]: info: TidalConnect service stoped! Sep 02 17:26:15 hub volumio[1000]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Sep 02 17:26:15 hub volumio[1000]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Sep 02 17:26:15 hub sudo[2811]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Sep 02 17:26:15 hub sudo[2811]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 17:26:15 hub sudo[2811]: pam_unix(sudo:session): session closed for user root Sep 02 17:26:15 hub volumio[1000]: info: TidalConnect service started! Sep 02 17:26:16 hub volumio[1000]: info: TidalConnect service started! Sep 02 17:26:17 hub volumio[1000]: info: TidalConnect service started! Sep 02 17:26:17 hub volumio[1000]: info: TidalConnect service started! Sep 02 17:26:18 hub volumio[1000]: info: TidalConnect service started! Sep 02 17:26:24 hub volumio[1000]: info: touch_display: Un-/commenting gpu_mem settings in /boot/config.txt. Sep 02 17:26:24 hub sudo[2834]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Sep 02 17:26:24 hub sudo[2834]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 17:26:24 hub sudo[2834]: pam_unix(sudo:session): session closed for user root Sep 02 17:26:24 hub sudo[2837]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start getty@tty1.service Sep 02 17:26:24 hub sudo[2837]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 17:26:24 hub sudo[2840]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl enable getty@tty1.service Sep 02 17:26:24 hub sudo[2840]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 17:26:24 hub systemd[1]: Created slice system-getty.slice. Sep 02 17:26:24 hub volumio[1000]: info: touch_display: Using /boot/config.txt instead of /boot/userconfig.txt. Sep 02 17:26:24 hub volumio[1000]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Sep 02 17:26:24 hub sudo[2844]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumio-kiosk.service Sep 02 17:26:24 hub sudo[2844]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 17:26:25 hub systemd[1]: Started Getty on tty1. Sep 02 17:26:25 hub sudo[2837]: pam_unix(sudo:session): session closed for user root Sep 02 17:26:25 hub volumio[1000]: info: touch_display: systemctl start getty@tty1.service succeeded. Sep 02 17:26:25 hub systemd[1]: Reloading. Sep 02 17:26:25 hub systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Sep 02 17:26:25 hub systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed. Sep 02 17:26:25 hub systemd[1]: Stopping Volumio Kiosk... Sep 02 17:26:25 hub startx[1333]: xinit: connection to X server lost Sep 02 17:26:25 hub startx[1333]: [1B blob data] Sep 02 17:26:25 hub sudo[2840]: pam_unix(sudo:session): session closed for user root Sep 02 17:26:25 hub volumio[1000]: info: touch_display: systemctl enable getty@tty1.service succeeded. Sep 02 17:26:25 hub startx[1333]: waiting for X server to shut down (II) Server terminated successfully (0). Closing log file. Sep 02 17:26:25 hub volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 02 17:26:26 hub startx[1333]: xinit: unexpected signal 15 Sep 02 17:26:26 hub systemd[1]: volumio-kiosk.service: Main process exited, code=exited, status=1/FAILURE Sep 02 17:26:26 hub systemd[1]: volumio-kiosk.service: Failed with result 'exit-code'. Sep 02 17:26:26 hub systemd[1]: Stopped Volumio Kiosk. Sep 02 17:26:26 hub sudo[2844]: pam_unix(sudo:session): session closed for user root Sep 02 17:26:26 hub volumio[1000]: info: touch_display: systemctl stop volumio-kiosk.service succeeded. Sep 02 17:26:26 hub volumio[1000]: info: Disabling plugin touch_display Sep 02 17:26:26 hub volumio[1000]: info: Done. Sep 02 17:26:27 hub sudo[2868]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sed -i s/localhost:4004/localhost:3000/g /opt/volumiokiosk.sh Sep 02 17:26:27 hub sudo[2868]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 17:26:27 hub sudo[2868]: pam_unix(sudo:session): session closed for user root Sep 02 17:26:27 hub volumio[1000]: [sudo] password for volumio: info: [now-playing] Executing /usr/bin/sudo /bin/systemctl status volumio-kiosk Sep 02 17:26:27 hub sudo[2871]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status volumio-kiosk Sep 02 17:26:27 hub sudo[2871]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 17:26:27 hub sudo[2871]: pam_unix(sudo:session): session closed for user root Sep 02 17:26:27 hub volumio[1000]: error: [now-playing] Failed to execute systemctl command status on volumio-kiosk: Error: Command failed: /usr/bin/sudo /bin/systemctl status volumio-kiosk Sep 02 17:26:27 hub volumio[1000]: at ChildProcess.exithandler (child_process.js:308:12) Sep 02 17:26:27 hub volumio[1000]: at ChildProcess.emit (events.js:315:20) Sep 02 17:26:27 hub volumio[1000]: at maybeClose (internal/child_process.js:1048:16) Sep 02 17:26:27 hub volumio[1000]: at Process.ChildProcess._handle.onexit (internal/child_process.js:288:5) Sep 02 17:26:27 hub volumio[1000]: warn: [now-playing] MyBackgroundMonitor stopped Sep 02 17:26:27 hub volumio[1000]: info: Disabling plugin now_playing Sep 02 17:26:27 hub volumio[1000]: info: Done. Sep 02 17:26:28 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 02 17:26:28 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 02 17:26:28 hub volumio[1000]: info: Discovery: Getting this device information Sep 02 17:26:28 hub volumio[1000]: info: CoreCommandRouter::volumioGetState Sep 02 17:26:28 hub volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 02 17:26:28 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 02 17:26:30 hub volumio[1000]: info: Disabling plugin Systeminfo Sep 02 17:26:30 hub volumio[1000]: info: Done. Sep 02 17:26:32 hub volumio[1000]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 02 17:26:32 hub volumio[1000]: info: [ASDebug] Setting UI defaults Sep 02 17:26:32 hub volumio[1000]: info: [ASDebug] Port: 24 Sep 02 17:26:32 hub volumio[1000]: info: [ASDebug] Inverted: false Sep 02 17:26:32 hub volumio[1000]: info: [ASDebug] Latched: false Sep 02 17:26:32 hub volumio[1000]: info: [ASDebug] On pulse width: 500 Sep 02 17:26:32 hub volumio[1000]: info: [ASDebug] Off pulse width: 500 Sep 02 17:26:35 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 02 17:26:35 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Sep 02 17:26:37 hub go-librespot[1331]: time="2025-09-02T17:26:37-07:00" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" Sep 02 17:26:37 hub volumio[1000]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify Sep 02 17:26:37 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 17:26:37 hub volumio[1000]: Cannot find translation for source SoundCloud Sep 02 17:26:37 hub volumio[1000]: Cannot find translation for source YouTube2 Sep 02 17:26:37 hub volumio[1000]: Cannot find translation for source Scarlett 2i4 USB Sep 02 17:26:37 hub volumio[1000]: Cannot find translation for source TIDAL Sep 02 17:26:37 hub sudo[2888]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop go-librespot-daemon.service Sep 02 17:26:37 hub sudo[2888]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 17:26:37 hub volumio[1000]: info: Disabling plugin spop Sep 02 17:26:37 hub volumio[1000]: info: Done. Sep 02 17:26:37 hub volumio[1000]: info: Connection to go-librespot Websocket closed Sep 02 17:26:37 hub systemd[1]: Stopping go-librespot Daemon... Sep 02 17:26:37 hub systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Sep 02 17:26:37 hub systemd[1]: go-librespot-daemon.service: Succeeded. Sep 02 17:26:37 hub systemd[1]: Stopped go-librespot Daemon. Sep 02 17:26:37 hub sudo[2888]: pam_unix(sudo:session): session closed for user root Sep 02 17:26:37 hub volumio[1000]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 02 17:26:38 hub volumio[1000]: info: Enabling plugin spop Sep 02 17:26:38 hub volumio[1000]: info: Loading plugin "spop"... Sep 02 17:26:38 hub volumio[1000]: info: PLUGIN START: spop Sep 02 17:26:38 hub volumio[1000]: info: Creating Spotify config file Sep 02 17:26:38 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 17:26:38 hub volumio[1000]: info: Done. Sep 02 17:26:38 hub volumio[1000]: info: Spotify config file written Sep 02 17:26:38 hub volumio[1000]: info: No need to fix Spotify hosts Sep 02 17:26:38 hub sudo[2891]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 02 17:26:38 hub sudo[2891]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 17:26:38 hub systemd[1]: Started go-librespot Daemon. Sep 02 17:26:38 hub go-librespot[2893]: go-librespot daemon starting... Sep 02 17:26:38 hub sudo[2891]: pam_unix(sudo:session): session closed for user root Sep 02 17:26:38 hub go-librespot[2893]: time="2025-09-02T17:26:38-07:00" level=info msg="running go-librespot 0.3.2" Sep 02 17:26:38 hub go-librespot[2893]: time="2025-09-02T17:26:38-07:00" level=debug msg="app state loaded" Sep 02 17:26:38 hub go-librespot[2893]: time="2025-09-02T17:26:38-07:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 02 17:26:38 hub go-librespot[2893]: time="2025-09-02T17:26:38-07:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Sep 02 17:26:38 hub go-librespot[2893]: time="2025-09-02T17:26:38-07:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Sep 02 17:26:38 hub go-librespot[2893]: time="2025-09-02T17:26:38-07:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Sep 02 17:26:38 hub go-librespot[2893]: time="2025-09-02T17:26:38-07:00" level=info msg="zeroconf server listening on port 35023" Sep 02 17:26:38 hub volumio[1000]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 02 17:26:38 hub volumio[1000]: SPOTIFY: BQDw_bB06E6oBGimfAF4Q25U9XdGf6Optk0apgXIPELrsL2WfGKxLLHZCcr_fyDt0kC8W6qQ7hCb1P2RdDjnG3bnHDNTHCSCtuH6TKJzGILVtV-t2L0NUCKan1eO12L3fcGHWbaZnaPL6nOGIm8mpkCXh1BRnXTkLTbGMSf4k9bWxr9YMuEe1F4LjVIPaVyvqj_OgFEPJOiLFrxX3fcFxGniPjhXeAsubJ45OUCDBl7kiT0Mh7-h2W14hUSUe-Be3sS-SgCC8voseEhVdpTp48Kjd53M4qGxCUu0g2BgRCmKbXeCJ6U Sep 02 17:26:38 hub volumio[1000]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 02 17:26:38 hub volumio[1000]: info: New Spotify access token = BQDw_bB06E6oBGimfAF4Q25U9XdGf6Optk0apgXIPELrsL2WfGKxLLHZCcr_fyDt0kC8W6qQ7hCb1P2RdDjnG3bnHDNTHCSCtuH6TKJzGILVtV-t2L0NUCKan1eO12L3fcGHWbaZnaPL6nOGIm8mpkCXh1BRnXTkLTbGMSf4k9bWxr9YMuEe1F4LjVIPaVyvqj_OgFEPJOiLFrxX3fcFxGniPjhXeAsubJ45OUCDBl7kiT0Mh7-h2W14hUSUe-Be3sS-SgCC8voseEhVdpTp48Kjd53M4qGxCUu0g2BgRCmKbXeCJ6U Sep 02 17:26:38 hub volumio[1000]: info: Spotify credentials grant success - running version from March 24, 2019 Sep 02 17:26:39 hub go-librespot[2893]: time="2025-09-02T17:26:39-07:00" level=debug msg="obtained new client token: AAAfafyZk5fbIBf+JgF07YqTHiThDr0KNovA9T2Dd/TR56Z5yC+9zFJRHF+/Lr8vFy0ponihSuSpaZUnn//zbVs8y1q7ktJohtx9UMuSYX3Xr7OR9WEa/4klU+YE1BuskebiIi9xBBPsajN2DqL7MIxeIlRS9QLIIwzZzdHBLy9TqUhKOn8RYW2I1KSd7qFq+R9cNeC0ghz7ScdR/411ImCSwRiFfSLvwpU9zyO17G+r3POHgkTDN1k=" Sep 02 17:26:39 hub volumio[1000]: SPOTIFY: User informations: {"country":"US","display_name":"Boston Daniels","email":"bosman112345@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/31jlxxmnikslb5avtjurknchkdeu"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/31jlxxmnikslb5avtjurknchkdeu","id":"31jlxxmnikslb5avtjurknchkdeu","images":[],"product":"premium","type":"user","uri":"spotify:user:31jlxxmnikslb5avtjurknchkdeu"} Sep 02 17:26:39 hub volumio[1000]: info: Spotify Successfully logged in Sep 02 17:26:39 hub volumio[1000]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 17:26:39 hub volumio[1000]: info: [1756859199139] CoreMusicLibrary::Adding element Spotify Sep 02 17:26:39 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 17:26:39 hub volumio[1000]: Cannot find translation for source SoundCloud Sep 02 17:26:39 hub volumio[1000]: Cannot find translation for source YouTube2 Sep 02 17:26:39 hub volumio[1000]: Cannot find translation for source Scarlett 2i4 USB Sep 02 17:26:39 hub volumio[1000]: Cannot find translation for source TIDAL Sep 02 17:26:39 hub volumio[1000]: Cannot find translation for source Spotify Sep 02 17:26:39 hub go-librespot[2893]: time="2025-09-02T17:26:39-07:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Sep 02 17:26:39 hub go-librespot[2893]: time="2025-09-02T17:26:39-07:00" level=debug msg="completed keyexchange" Sep 02 17:26:39 hub go-librespot[2893]: time="2025-09-02T17:26:39-07:00" level=debug msg="completed challenge" Sep 02 17:26:39 hub go-librespot[2893]: time="2025-09-02T17:26:39-07:00" level=info msg="authenticated AP" username="31************************eu" Sep 02 17:26:39 hub go-librespot[2893]: time="2025-09-02T17:26:39-07:00" level=info msg="authenticated Login5" username="31************************eu" Sep 02 17:26:39 hub go-librespot[2893]: time="2025-09-02T17:26:39-07:00" level=debug msg="initializing zeroconf session" username="31************************eu" Sep 02 17:26:39 hub go-librespot[2893]: time="2025-09-02T17:26:39-07:00" level=debug msg="dealer connection opened" Sep 02 17:26:39 hub go-librespot[2893]: time="2025-09-02T17:26:39-07:00" level=trace msg="starting accesspoint recv loop" Sep 02 17:26:39 hub go-librespot[2893]: time="2025-09-02T17:26:39-07:00" level=trace msg="starting dealer recv loop" Sep 02 17:26:39 hub go-librespot[2893]: time="2025-09-02T17:26:39-07:00" level=trace msg="received accesspoint ping" Sep 02 17:26:39 hub go-librespot[2893]: time="2025-09-02T17:26:39-07:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Sep 02 17:26:39 hub go-librespot[2893]: time="2025-09-02T17:26:39-07:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Sep 02 17:26:39 hub go-librespot[2893]: time="2025-09-02T17:26:39-07:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Sep 02 17:26:39 hub go-librespot[2893]: time="2025-09-02T17:26:39-07:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Sep 02 17:26:39 hub go-librespot[2893]: time="2025-09-02T17:26:39-07:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Sep 02 17:26:39 hub go-librespot[2893]: time="2025-09-02T17:26:39-07:00" level=debug msg="received connection id: ZDBkM2Y3MjAtNzhk...NkIwNzY3NzkyMA==" Sep 02 17:26:39 hub volumio[1000]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSoundCloud Sep 02 17:26:39 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 17:26:39 hub volumio[1000]: Cannot find translation for source YouTube2 Sep 02 17:26:39 hub volumio[1000]: Cannot find translation for source Scarlett 2i4 USB Sep 02 17:26:39 hub volumio[1000]: Cannot find translation for source TIDAL Sep 02 17:26:39 hub volumio[1000]: Cannot find translation for source Spotify Sep 02 17:26:39 hub volumio[1000]: info: Disabling plugin soundcloud Sep 02 17:26:39 hub volumio[1000]: info: Done. Sep 02 17:26:39 hub go-librespot[2893]: time="2025-09-02T17:26:39-07:00" level=trace msg="received accesspoint pong ack" Sep 02 17:26:40 hub go-librespot[2893]: time="2025-09-02T17:26:40-07:00" level=debug msg="put connect state because NEW_DEVICE" Sep 02 17:26:41 hub volumio[1000]: info: go-librespot daemon successfully initialized Sep 02 17:26:42 hub volumio[1000]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 02 17:26:42 hub volumio[1000]: info: [youtube2] InnertubeLoader: creating Innertube instance... Sep 02 17:26:47 hub volumio[1000]: [YOUTUBEJS][Player]: Failed to extract signature decipher algorithm. Sep 02 17:26:47 hub volumio[1000]: info: [youtube2] InnertubeLoader: obtaining po_token by visitorData... Sep 02 17:26:47 hub volumio[1000]: info: Initializing connection to go-librespot Websocket Sep 02 17:26:47 hub go-librespot[2893]: time="2025-09-02T17:26:47-07:00" level=debug msg="new websocket client" Sep 02 17:26:47 hub volumio[1000]: info: Connection to go-librespot Websocket established Sep 02 17:26:48 hub volumio[1000]: info: [youtube2] InnertubeLoader: obtained po_token (expires in 43200 seconds) Sep 02 17:26:48 hub volumio[1000]: info: [youtube2] InnertubeLoader: re-create Innertube instance with po_token Sep 02 17:26:48 hub volumio[1000]: info: [youtube2] InnertubeLoader: creating Innertube instance with po_token... Sep 02 17:26:51 hub volumio[1000]: [YOUTUBEJS][Player]: Failed to extract signature decipher algorithm. Sep 02 17:26:51 hub volumio[1000]: info: [youtube2] InnertubeLoader: going to refresh po_token in 43100 seconds Sep 02 17:26:51 hub volumio[1000]: info: Getting Spotify volume Sep 02 17:26:51 hub volumio[1000]: info: Spotify volume: 100 Sep 02 17:26:51 hub volumio[1000]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Sep 02 17:26:51 hub volumio[1000]: info: CoreCommandRouter::volumioGetState Sep 02 17:26:51 hub volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 02 17:26:51 hub volumio[1000]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Sep 02 17:26:51 hub volumio[1000]: SPOTIFY: SPOTIFY VOLUME 100 Sep 02 17:26:51 hub volumio[1000]: SPOTIFY: VOLUMIO VOLUME 50 Sep 02 17:26:51 hub volumio[1000]: SPOTIFY: DELTA VOLUME ENOUGH: true Sep 02 17:26:51 hub volumio[1000]: info: Setting Spotify Volume from Volumio: 50 Sep 02 17:26:52 hub volumio[1000]: SPOTIFY: SETTING SPOTIFY VOLUME 50 Sep 02 17:26:52 hub volumio[1000]: info: Sending Spotify command with payload to local API: /player/volume Sep 02 17:26:52 hub go-librespot[2893]: time="2025-09-02T17:26:52-07:00" level=debug msg="update volume requested to 32767/65535" Sep 02 17:26:52 hub go-librespot[2893]: time="2025-09-02T17:26:52-07:00" level=debug msg="put connect state because VOLUME_CHANGED" Sep 02 17:26:52 hub go-librespot[2893]: time="2025-09-02T17:26:52-07:00" level=trace msg="emitting websocket event: volume" Sep 02 17:26:52 hub volumio[1000]: SPOTIFY: received: {"type":"volume","data":{"value":50,"max":100}} Sep 02 17:26:52 hub volumio[1000]: SPOTIFY: RECEIVED SPOTIFY VOLUME 50 Sep 02 17:26:58 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 02 17:26:58 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 02 17:26:58 hub volumio[1000]: info: Discovery: Getting this device information Sep 02 17:26:58 hub volumio[1000]: info: CoreCommandRouter::volumioGetState Sep 02 17:26:58 hub volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 02 17:26:58 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 02 17:26:58 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 02 17:26:58 hub volumio[1000]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Sep 02 17:27:05 hub volumio[1000]: info: [yt-cast-receiver] Player.reset() Sep 02 17:27:05 hub volumio[1000]: info: CoreCommandRouter::volumioGetState Sep 02 17:27:05 hub volumio[1000]: info: CorePlayQueue::getTrack 0 Sep 02 17:27:05 hub volumio[1000]: info: Disabling plugin ytcr Sep 02 17:27:05 hub volumio[1000]: info: Done. Sep 02 17:27:06 hub volumio[1000]: info: [ASDebug] Port: 24 Sep 02 17:27:06 hub volumio[1000]: info: [ASDebug] Inverted: false Sep 02 17:27:06 hub volumio[1000]: info: [ASDebug] Delay: 720 Sep 02 17:27:06 hub volumio[1000]: info: [ASDebug] Port: 24 Sep 02 17:27:06 hub volumio[1000]: info: [ASDebug] Inverted: false Sep 02 17:27:06 hub volumio[1000]: info: [ASDebug] Delay: 720 Sep 02 17:27:06 hub volumio[1000]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 02 17:27:06 hub volumio[1000]: Error: EBADF: bad file descriptor, close Sep 02 17:27:06 hub volumio[1000]: at Object.closeSync (fs.js:439:3) Sep 02 17:27:06 hub volumio[1000]: at Object.closeSync (/volumio/node_modules/graceful-fs/graceful-fs.js:74:20) Sep 02 17:27:06 hub volumio[1000]: at Object.closeSync (/data/plugins/system_controller/ampswitch/node_modules/graceful-fs/graceful-fs.js:51:27) Sep 02 17:27:06 hub volumio[1000]: at Gpio.unexport (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:307:8) Sep 02 17:27:06 hub volumio[1000]: at AmpSwitchController.freeGPIO (/data/plugins/system_controller/ampswitch/index.js:242:19) Sep 02 17:27:06 hub volumio[1000]: at AmpSwitchController.onStop (/data/plugins/system_controller/ampswitch/index.js:90:10) Sep 02 17:27:06 hub volumio[1000]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Sep 02 17:27:06 hub volumio[1000]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1883:8) Sep 02 17:27:06 hub volumio[1000]: at CoreCommandRouter.disableAndStopPlugin (/volumio/app/index.js:1556:29) Sep 02 17:27:06 hub volumio[1000]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1401:47) { Sep 02 17:27:06 hub volumio[1000]: errno: -9, Sep 02 17:27:06 hub volumio[1000]: syscall: 'close', Sep 02 17:27:06 hub volumio[1000]: code: 'EBADF' Sep 02 17:27:06 hub volumio[1000]: } Sep 02 17:27:06 hub volumio[1000]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 02 17:27:07 hub sudo[3002]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-02 17:26 Sep 02 17:27:07 hub sudo[3002]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"