-- Logs begin at Wed 2025-05-21 13:50:54 CEST, end at Wed 2025-05-21 14:08:32 CEST. -- May 21 14:06:59 lez systemd[1]: Stopped Volumio Qobuz Connect Service. May 21 14:07:00 lez systemd[1]: Started Volumio Qobuz Connect Service. May 21 14:07:00 lez volumio[794]: info: VolumeController::SetAlsaVolume80 May 21 14:07:00 lez systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=11/SEGV May 21 14:07:00 lez systemd[1]: qobuz-connect.service: Failed with result 'signal'. May 21 14:07:01 lez volumio[794]: info: CoreStateMachine::pushState May 21 14:07:01 lez volumio[794]: info: CorePlayQueue::getTrack 0 May 21 14:07:01 lez volumio[794]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 14:07:01 lez volumio[794]: info: CoreCommandRouter::volumioPushState May 21 14:07:01 lez volumio[794]: info: MRS: Pushing multiroomSync output update for this device May 21 14:07:01 lez volumio[794]: info: MRS: Pushing multiroomSync output May 21 14:07:01 lez volumio[794]: info: CoreCommandRouter::volumioGetState May 21 14:07:01 lez volumio[794]: info: CorePlayQueue::getTrack 0 May 21 14:07:01 lez volumio[794]: error: Help! Some callbacks for volumioPushState are crashing! May 21 14:07:01 lez volumio[794]: error: Cannot read property 'sendVolumeMute' of undefined May 21 14:07:01 lez volumio[794]: info: CoreStateMachine::pushState May 21 14:07:01 lez volumio[794]: info: CorePlayQueue::getTrack 0 May 21 14:07:01 lez volumio[794]: info: CoreCommandRouter::volumioPushState May 21 14:07:01 lez volumio[794]: info: MRS: Pushing multiroomSync output update for this device May 21 14:07:01 lez volumio[794]: info: MRS: Pushing multiroomSync output May 21 14:07:01 lez volumio[794]: info: CoreCommandRouter::volumioGetState May 21 14:07:01 lez volumio[794]: info: CorePlayQueue::getTrack 0 May 21 14:07:01 lez volumio[794]: error: Help! Some callbacks for volumioPushState are crashing! May 21 14:07:01 lez volumio[794]: error: Cannot read property 'sendVolumeMute' of undefined May 21 14:07:01 lez volumio[794]: info: CoreStateMachine::pushState May 21 14:07:01 lez volumio[794]: info: CorePlayQueue::getTrack 0 May 21 14:07:01 lez volumio[794]: info: CoreCommandRouter::volumioPushState May 21 14:07:01 lez volumio[794]: info: MRS: Pushing multiroomSync output update for this device May 21 14:07:01 lez vtcs[3958]: Segmentation fault May 21 14:07:01 lez systemd[1]: vtcs.service: Main process exited, code=exited, status=139/n/a May 21 14:07:01 lez volumio[794]: info: MRS: Pushing multiroomSync output May 21 14:07:01 lez volumio[794]: info: CoreCommandRouter::volumioGetState May 21 14:07:01 lez systemd[1]: vtcs.service: Failed with result 'exit-code'. May 21 14:07:01 lez volumio[794]: info: CorePlayQueue::getTrack 0 May 21 14:07:01 lez volumio[794]: error: Help! Some callbacks for volumioPushState are crashing! May 21 14:07:01 lez volumio[794]: error: Cannot read property 'sendVolumeMute' of undefined May 21 14:07:02 lez systemd[1]: vtcs.service: Service RestartSec=100ms expired, scheduling restart. May 21 14:07:02 lez systemd[1]: vtcs.service: Scheduled restart job, restart counter is at 1. May 21 14:07:02 lez systemd[1]: Stopped Volumio Tidal Connect Service. May 21 14:07:02 lez systemd[1]: Started Volumio Tidal Connect Service. May 21 14:07:02 lez volumio[794]: info: Shairport-Sync Started May 21 14:07:02 lez volumio[794]: info: Shairport-Sync Started May 21 14:07:02 lez volumio[794]: info: Asound.conf file unchanged, so no further update is needed May 21 14:07:02 lez volumio[794]: info: Output device has changed, restarting MPD May 21 14:07:03 lez volumio[794]: info: Output device has changed, restarting Shairport Sync May 21 14:07:03 lez volumio[794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 14:07:03 lez volumio[794]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 21 14:07:03 lez sudo[3988]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 21 14:07:03 lez sudo[3988]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 14:07:04 lez sudo[3988]: pam_unix(sudo:session): session closed for user root May 21 14:07:04 lez go-librespot[1085]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:912:(_snd_pcm_volumioswitch_advance) PCM volumioMultiRoomServer cannot write to target PCM softvolume as it has failed its update check. May 21 14:07:04 lez go-librespot[1085]: time="2025-05-21T14:07:04+02:00" level=debug msg="fetched chunk 5/10, size: 524288" uri="spotify:track:2oHWZ8kZzyHMGK0NCqdRs0" May 21 14:07:04 lez sudo[3990]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 21 14:07:04 lez sudo[3990]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 14:07:05 lez systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 21 14:07:05 lez systemd[1]: mpd.service: Succeeded. May 21 14:07:05 lez systemd[1]: Stopped Music Player Daemon. May 21 14:07:05 lez systemd[1]: Starting Music Player Daemon... May 21 14:07:05 lez volumio[794]: xcb_connection_has_error() returned true May 21 14:07:05 lez volumio[794]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 21 14:07:05 lez volumio[794]: xcb_connection_has_error() returned true May 21 14:07:05 lez volumio[794]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 21 14:07:05 lez volumio[794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 14:07:05 lez volumio[794]: info: QobuzConnect: setDeactiveState invoked May 21 14:07:05 lez volumio[794]: info: CoreCommandRouter::volumioGetState May 21 14:07:05 lez volumio[794]: info: CorePlayQueue::getTrack 0 May 21 14:07:05 lez volumio[794]: error: Error starting TidalConnect Cannot read property 'sendRevoke' of undefined {"stack":"TypeError: Cannot read property 'sendRevoke' of undefined\n at ControllerTidalconnect.stopTidalConnect (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:8526)\n at ControllerTidalconnect.restartService (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:8818)\n at /volumio/node_modules/v-conf/index.js:165:13\n at /volumio/node_modules/multimap/index.js:150:9\n at Array.forEach ()\n at /volumio/node_modules/multimap/index.js:149:13\n at mapEach (/volumio/node_modules/multimap/index.js:9:5)\n at Multimap.forEachEntry (/volumio/node_modules/multimap/index.js:143:5)\n at Multimap.forEach (/volumio/node_modules/multimap/index.js:148:10)\n at Config.set (/volumio/node_modules/v-conf/index.js:162:20)\n at Promise._successFn (/volumio/app/plugins/audio_interface/alsa_controller/index.js:2107:39)\n at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28)\n at processTicksAndRejections (internal/process/task_queues.js:75:11)"} May 21 14:07:05 lez volumio[794]: info: Preparing to generate the ALSA configuration file May 21 14:07:05 lez systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart. May 21 14:07:05 lez systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 4. May 21 14:07:06 lez systemd[1]: Stopped Volumio Qobuz Connect Service. May 21 14:07:06 lez systemd[1]: Started Volumio Qobuz Connect Service. May 21 14:07:06 lez sudo[3996]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 21 14:07:06 lez sudo[3996]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 14:07:06 lez sudo[3996]: pam_unix(sudo:session): session closed for user root May 21 14:07:06 lez systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=11/SEGV May 21 14:07:06 lez systemd[1]: qobuz-connect.service: Failed with result 'signal'. May 21 14:07:06 lez vtcs[3985]: Segmentation fault May 21 14:07:06 lez systemd[1]: vtcs.service: Main process exited, code=exited, status=139/n/a May 21 14:07:06 lez systemd[1]: vtcs.service: Failed with result 'exit-code'. May 21 14:07:06 lez systemd[1]: vtcs.service: Service RestartSec=100ms expired, scheduling restart. May 21 14:07:06 lez systemd[1]: vtcs.service: Scheduled restart job, restart counter is at 2. May 21 14:07:07 lez systemd[1]: Stopped Volumio Tidal Connect Service. May 21 14:07:07 lez systemd[1]: Started Volumio Tidal Connect Service. May 21 14:07:07 lez sudo[4002]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 21 14:07:07 lez sudo[4002]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 14:07:07 lez systemd[1]: Stopping Volumio Tidal Connect Service... May 21 14:07:07 lez systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM May 21 14:07:07 lez systemd[1]: vtcs.service: Succeeded. May 21 14:07:07 lez systemd[1]: Stopped Volumio Tidal Connect Service. May 21 14:07:08 lez sudo[4002]: pam_unix(sudo:session): session closed for user root May 21 14:07:08 lez sudo[4011]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service May 21 14:07:08 lez sudo[4011]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 14:07:08 lez volumio[794]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 21 14:07:08 lez volumio[794]: ------------------------------------ BT MESSAGE: BT STATUS: running May 21 14:07:08 lez volumio[794]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 21 14:07:08 lez volumio[794]: ------------------------------------ BT MESSAGE: BT STATUS: running May 21 14:07:08 lez sudo[4011]: pam_unix(sudo:session): session closed for user root May 21 14:07:08 lez volumio[794]: ------------------------------------ BT MESSAGE: BT STATUS: running May 21 14:07:09 lez sudo[4018]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service May 21 14:07:09 lez sudo[4018]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 14:07:09 lez volumio[794]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 May 21 14:07:09 lez volumio[794]: SPOTIFY: SPOTIFY VOLUME 51 May 21 14:07:09 lez volumio[794]: SPOTIFY: VOLUMIO VOLUME 80 May 21 14:07:09 lez volumio[794]: SPOTIFY: DELTA VOLUME ENOUGH: true May 21 14:07:09 lez volumio[794]: info: Setting Spotify Volume from Volumio: 80 May 21 14:07:09 lez volumio[794]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 May 21 14:07:09 lez volumio[794]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 May 21 14:07:09 lez systemd[1]: Stopped Volumio Qobuz Connect Service. May 21 14:07:09 lez volumio[794]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 May 21 14:07:09 lez systemd[1]: Started Volumio Qobuz Connect Service. May 21 14:07:09 lez sudo[4018]: pam_unix(sudo:session): session closed for user root May 21 14:07:10 lez go-librespot[1085]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:912:(_snd_pcm_volumioswitch_advance) PCM volumioMultiRoomServer cannot write to target PCM softvolume as it has failed its update check. May 21 14:07:10 lez systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=11/SEGV May 21 14:07:10 lez systemd[1]: qobuz-connect.service: Failed with result 'signal'. May 21 14:07:10 lez volumio[794]: info: TidalConnect service started! May 21 14:07:10 lez volumio[794]: info: TidalConnect service started! May 21 14:07:10 lez volumio[794]: info: TidalConnect service started! May 21 14:07:10 lez volumio[794]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf May 21 14:07:10 lez volumio[794]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 21 14:07:10 lez volumio[794]: info: Reading ALSA contributions from plugins. May 21 14:07:11 lez volumio[794]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 May 21 14:07:11 lez volumio[794]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 May 21 14:07:11 lez volumio[794]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined May 21 14:07:11 lez volumio[794]: info: MPD Permissions set May 21 14:07:11 lez volumio[794]: SPOTIFY: SETTING SPOTIFY VOLUME 80 May 21 14:07:11 lez volumio[794]: info: Sending Spotify command with payload to local API: /player/volume May 21 14:07:11 lez volumio[794]: info: TidalConnect service stoped! May 21 14:07:11 lez go-librespot[1085]: time="2025-05-21T14:07:11+02:00" level=debug msg="update volume to 52428/65535" May 21 14:07:12 lez volumio[794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 14:07:12 lez volumio[794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 14:07:12 lez volumio[794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 14:07:12 lez volumio[794]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 21 14:07:12 lez volumio[794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 14:07:12 lez volumio[794]: info: CoreCommandRouter::volumioGetState May 21 14:07:12 lez volumio[794]: info: CorePlayQueue::getTrack 0 May 21 14:07:13 lez go-librespot[1085]: time="2025-05-21T14:07:13+02:00" level=debug msg="put connect state because VOLUME_CHANGED" May 21 14:07:13 lez go-librespot[1085]: time="2025-05-21T14:07:13+02:00" level=trace msg="emitting websocket event: volume" May 21 14:07:13 lez volumio-remote-updater[409]: [2025-05-21 14:07:13] [connect] Successful connection May 21 14:07:13 lez volumio[794]: info: CoreCommandRouter::volumioGetState May 21 14:07:13 lez volumio[794]: info: CorePlayQueue::getTrack 0 May 21 14:07:13 lez volumio[794]: SPOTIFY: received: {"type":"volume","data":{"value":80,"max":100}} May 21 14:07:13 lez volumio[794]: SPOTIFY: RECEIVED SPOTIFY VOLUME 80 May 21 14:07:14 lez volumio[794]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 21 14:07:14 lez volumio[794]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 21 14:07:14 lez volumio[794]: info: Starting Shairport Sync May 21 14:07:14 lez volumio-remote-updater[409]: [2025-05-21 14:07:14] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1747829233 101 May 21 14:07:14 lez sudo[4082]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 21 14:07:14 lez sudo[4082]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 14:07:14 lez volumio[794]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 6 May 21 14:07:14 lez volumio[794]: info: Asound.conf file unchanged, so no further update is needed May 21 14:07:14 lez volumio[794]: info: Output device has changed, restarting MPD May 21 14:07:14 lez systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 21 14:07:14 lez systemd[1]: shairport-sync.service: Succeeded. May 21 14:07:14 lez systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 21 14:07:14 lez systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 21 14:07:15 lez sudo[4082]: pam_unix(sudo:session): session closed for user root May 21 14:07:15 lez volumio[794]: info: Output device has changed, restarting Shairport Sync May 21 14:07:15 lez systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart. May 21 14:07:15 lez systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 1. May 21 14:07:15 lez systemd[1]: Stopped Volumio Qobuz Connect Service. May 21 14:07:15 lez volumio[794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 14:07:15 lez volumio[794]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 21 14:07:15 lez sudo[4086]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 21 14:07:15 lez systemd[1]: Started Volumio Qobuz Connect Service. May 21 14:07:15 lez sudo[4086]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 14:07:15 lez sudo[4086]: pam_unix(sudo:session): session closed for user root May 21 14:07:16 lez systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=11/SEGV May 21 14:07:16 lez systemd[1]: qobuz-connect.service: Failed with result 'signal'. May 21 14:07:16 lez sudo[4088]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 21 14:07:16 lez sudo[4088]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 14:07:16 lez systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 21 14:07:16 lez systemd[1]: mpd.service: Succeeded. May 21 14:07:16 lez systemd[1]: Stopped Music Player Daemon. May 21 14:07:16 lez systemd[1]: Starting Music Player Daemon... May 21 14:07:16 lez volumio[794]: xcb_connection_has_error() returned true May 21 14:07:16 lez volumio[794]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 21 14:07:16 lez volumio[794]: xcb_connection_has_error() returned true May 21 14:07:16 lez volumio[794]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 21 14:07:16 lez volumio[794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 14:07:16 lez volumio[794]: info: QobuzConnect: setDeactiveState invoked May 21 14:07:17 lez volumio[794]: info: CoreCommandRouter::volumioGetState May 21 14:07:17 lez volumio[794]: info: CorePlayQueue::getTrack 0 May 21 14:07:17 lez volumio[794]: error: Error starting TidalConnect Cannot read property 'sendRevoke' of undefined {"stack":"TypeError: Cannot read property 'sendRevoke' of undefined\n at ControllerTidalconnect.stopTidalConnect (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:8526)\n at ControllerTidalconnect.restartService (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:8818)\n at /volumio/node_modules/v-conf/index.js:165:13\n at /volumio/node_modules/multimap/index.js:150:9\n at Array.forEach ()\n at /volumio/node_modules/multimap/index.js:149:13\n at mapEach (/volumio/node_modules/multimap/index.js:9:5)\n at Multimap.forEachEntry (/volumio/node_modules/multimap/index.js:143:5)\n at Multimap.forEach (/volumio/node_modules/multimap/index.js:148:10)\n at Config.set (/volumio/node_modules/v-conf/index.js:162:20)\n at Promise._successFn (/volumio/app/plugins/audio_interface/alsa_controller/index.js:2107:39)\n at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28)\n at processTicksAndRejections (internal/process/task_queues.js:75:11)"} May 21 14:07:17 lez volumio[794]: info: Preparing to generate the ALSA configuration file May 21 14:07:17 lez sudo[4097]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 21 14:07:17 lez sudo[4097]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 14:07:17 lez sudo[4097]: pam_unix(sudo:session): session closed for user root May 21 14:07:18 lez sudo[4103]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 21 14:07:18 lez sudo[4103]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 14:07:18 lez go-librespot[1085]: time="2025-05-21T14:07:18+02:00" level=debug msg="fetched chunk 6/10, size: 524288" uri="spotify:track:2oHWZ8kZzyHMGK0NCqdRs0" May 21 14:07:18 lez sudo[4103]: pam_unix(sudo:session): session closed for user root May 21 14:07:18 lez sudo[4109]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service May 21 14:07:19 lez sudo[4109]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 14:07:19 lez volumio[794]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf May 21 14:07:19 lez volumio[794]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 21 14:07:19 lez volumio[794]: info: Reading ALSA contributions from plugins. May 21 14:07:19 lez sudo[4109]: pam_unix(sudo:session): session closed for user root May 21 14:07:19 lez sudo[4112]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 21 14:07:19 lez sudo[4112]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 14:07:19 lez volumio[794]: ------------------------------------ BT MESSAGE: BT STATUS: running May 21 14:07:19 lez sudo[4114]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service May 21 14:07:19 lez volumio[794]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 21 14:07:20 lez sudo[4114]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 14:07:20 lez systemd[1]: Started Volumio Tidal Connect Service. May 21 14:07:20 lez sudo[4112]: pam_unix(sudo:session): session closed for user root May 21 14:07:20 lez volumio[794]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 21 14:07:20 lez volumio[794]: ------------------------------------ BT MESSAGE: BT STATUS: running May 21 14:07:20 lez systemd[1]: Stopped Volumio Qobuz Connect Service. May 21 14:07:20 lez volumio[794]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 21 14:07:20 lez volumio[794]: ------------------------------------ BT MESSAGE: BT STATUS: running May 21 14:07:20 lez systemd[1]: Started Volumio Qobuz Connect Service. May 21 14:07:20 lez sudo[4114]: pam_unix(sudo:session): session closed for user root May 21 14:07:21 lez volumio[794]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined May 21 14:07:21 lez volumio[794]: info: MPD Permissions set May 21 14:07:21 lez volumio[794]: info: Shairport-Sync Started May 21 14:07:21 lez systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=11/SEGV May 21 14:07:21 lez systemd[1]: qobuz-connect.service: Failed with result 'signal'. May 21 14:07:21 lez volumio[794]: info: CoreCommandRouter::getUIConfigOnPlugin May 21 14:07:22 lez volumio[794]: info: TidalConnect service stoped! May 21 14:07:22 lez volumio[794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 14:07:22 lez volumio[794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 14:07:22 lez volumio[794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 14:07:22 lez volumio[794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 14:07:23 lez go-librespot[1085]: time="2025-05-21T14:07:23+02:00" level=trace msg="sent dealer ping" May 21 14:07:23 lez go-librespot[1085]: time="2025-05-21T14:07:23+02:00" level=trace msg="received dealer pong" May 21 14:07:23 lez volumio[794]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 21 14:07:23 lez volumio[794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 14:07:23 lez volumio[794]: info: TidalConnect service started! May 21 14:07:24 lez go-librespot[1085]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:912:(_snd_pcm_volumioswitch_advance) PCM volumioMultiRoomServer cannot write to target PCM softvolume as it has failed its update check. May 21 14:07:24 lez vtcs[4116]: Segmentation fault May 21 14:07:24 lez systemd[1]: vtcs.service: Main process exited, code=exited, status=139/n/a May 21 14:07:24 lez systemd[1]: vtcs.service: Failed with result 'exit-code'. May 21 14:07:24 lez systemd[1]: vtcs.service: Service RestartSec=100ms expired, scheduling restart. May 21 14:07:24 lez systemd[1]: vtcs.service: Scheduled restart job, restart counter is at 1. May 21 14:07:24 lez systemd[1]: Stopped Volumio Tidal Connect Service. May 21 14:07:24 lez systemd[1]: Started Volumio Tidal Connect Service. May 21 14:07:25 lez volumio[794]: xcb_connection_has_error() returned true May 21 14:07:25 lez volumio[794]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 21 14:07:25 lez volumio[794]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 21 14:07:25 lez volumio[794]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 21 14:07:25 lez volumio[794]: info: Getting Alsa Cards List without I2S DAC May 21 14:07:25 lez volumio[794]: xcb_connection_has_error() returned true May 21 14:07:25 lez volumio[794]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber May 21 14:07:26 lez volumio[794]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 21 14:07:26 lez systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart. May 21 14:07:26 lez systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 1. May 21 14:07:26 lez systemd[1]: Stopped Volumio Qobuz Connect Service. May 21 14:07:26 lez systemd[1]: Started Volumio Qobuz Connect Service. May 21 14:07:26 lez volumio[794]: xcb_connection_has_error() returned true May 21 14:07:27 lez systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=11/SEGV May 21 14:07:27 lez systemd[1]: qobuz-connect.service: Failed with result 'signal'. May 21 14:07:27 lez volumio[794]: xcb_connection_has_error() returned true May 21 14:07:27 lez volumio[794]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 21 14:07:27 lez volumio[794]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 21 14:07:27 lez volumio[794]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 21 14:07:27 lez volumio[794]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 21 14:07:27 lez volumio[794]: info: Starting Shairport Sync May 21 14:07:27 lez go-librespot[1085]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:912:(_snd_pcm_volumioswitch_advance) PCM volumioMultiRoomServer cannot write to target PCM softvolume as it has failed its update check. May 21 14:07:28 lez sudo[4160]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 21 14:07:28 lez sudo[4160]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 14:07:28 lez systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 21 14:07:28 lez systemd[1]: shairport-sync.service: Succeeded. May 21 14:07:29 lez volumio[794]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 21 14:07:29 lez volumio[794]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 21 14:07:29 lez systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 21 14:07:29 lez volumio[794]: ------------------------------------ BT MESSAGE: BT STATUS: running May 21 14:07:29 lez systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 21 14:07:29 lez volumio[794]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 21 14:07:29 lez vtcs[4149]: Segmentation fault May 21 14:07:29 lez systemd[1]: vtcs.service: Main process exited, code=exited, status=139/n/a May 21 14:07:29 lez volumio[794]: info: Asound.conf file unchanged, so no further update is needed May 21 14:07:29 lez volumio[794]: info: Output device has changed, restarting MPD May 21 14:07:29 lez systemd[1]: vtcs.service: Failed with result 'exit-code'. May 21 14:07:29 lez sudo[4160]: pam_unix(sudo:session): session closed for user root May 21 14:07:29 lez systemd[1]: vtcs.service: Service RestartSec=100ms expired, scheduling restart. May 21 14:07:29 lez systemd[1]: vtcs.service: Scheduled restart job, restart counter is at 2. May 21 14:07:29 lez systemd[1]: Stopped Volumio Tidal Connect Service. May 21 14:07:29 lez systemd[1]: Started Volumio Tidal Connect Service. May 21 14:07:30 lez go-librespot[1085]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:912:(_snd_pcm_volumioswitch_advance) PCM volumioMultiRoomServer cannot write to target PCM softvolume as it has failed its update check. May 21 14:07:30 lez volumio[794]: info: Output device has changed, restarting Shairport Sync May 21 14:07:30 lez volumio[794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 14:07:30 lez volumio[794]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 21 14:07:30 lez sudo[4167]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 21 14:07:30 lez sudo[4167]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 14:07:30 lez sudo[4167]: pam_unix(sudo:session): session closed for user root May 21 14:07:30 lez sudo[4171]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 21 14:07:30 lez sudo[4171]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 14:07:31 lez systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 21 14:07:31 lez systemd[1]: mpd.service: Succeeded. May 21 14:07:31 lez systemd[1]: Stopped Music Player Daemon. May 21 14:07:31 lez systemd[1]: Starting Music Player Daemon... May 21 14:07:31 lez volumio[794]: xcb_connection_has_error() returned true May 21 14:07:31 lez volumio[794]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 21 14:07:31 lez volumio[794]: xcb_connection_has_error() returned true May 21 14:07:31 lez volumio[794]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 21 14:07:31 lez volumio[794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 14:07:31 lez volumio[794]: info: QobuzConnect: setDeactiveState invoked May 21 14:07:32 lez volumio[794]: info: CoreCommandRouter::volumioGetState May 21 14:07:32 lez volumio[794]: info: CorePlayQueue::getTrack 0 May 21 14:07:32 lez volumio[794]: error: Error starting TidalConnect Cannot read property 'sendRevoke' of undefined {"stack":"TypeError: Cannot read property 'sendRevoke' of undefined\n at ControllerTidalconnect.stopTidalConnect (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:8526)\n at ControllerTidalconnect.restartService (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:8818)\n at /volumio/node_modules/v-conf/index.js:165:13\n at /volumio/node_modules/multimap/index.js:150:9\n at Array.forEach ()\n at /volumio/node_modules/multimap/index.js:149:13\n at mapEach (/volumio/node_modules/multimap/index.js:9:5)\n at Multimap.forEachEntry (/volumio/node_modules/multimap/index.js:143:5)\n at Multimap.forEach (/volumio/node_modules/multimap/index.js:148:10)\n at Config.set (/volumio/node_modules/v-conf/index.js:162:20)\n at Promise._successFn (/volumio/app/plugins/audio_interface/alsa_controller/index.js:2107:39)\n at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28)\n at processTicksAndRejections (internal/process/task_queues.js:75:11)"} May 21 14:07:32 lez volumio[794]: info: Preparing to generate the ALSA configuration file May 21 14:07:32 lez systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart. May 21 14:07:32 lez systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 2. May 21 14:07:32 lez go-librespot[1085]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:912:(_snd_pcm_volumioswitch_advance) PCM volumioMultiRoomServer cannot write to target PCM softvolume as it has failed its update check. May 21 14:07:32 lez systemd[1]: Stopped Volumio Qobuz Connect Service. May 21 14:07:32 lez systemd[1]: Started Volumio Qobuz Connect Service. May 21 14:07:33 lez sudo[4177]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 21 14:07:33 lez sudo[4177]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 14:07:33 lez sudo[4177]: pam_unix(sudo:session): session closed for user root May 21 14:07:33 lez systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=11/SEGV May 21 14:07:33 lez systemd[1]: qobuz-connect.service: Failed with result 'signal'. May 21 14:07:33 lez go-librespot[1085]: time="2025-05-21T14:07:33+02:00" level=debug msg="fetched chunk 7/10, size: 524288" uri="spotify:track:2oHWZ8kZzyHMGK0NCqdRs0" May 21 14:07:34 lez vtcs[4165]: Segmentation fault May 21 14:07:34 lez systemd[1]: vtcs.service: Main process exited, code=exited, status=139/n/a May 21 14:07:34 lez systemd[1]: vtcs.service: Failed with result 'exit-code'. May 21 14:07:34 lez volumio[794]: xcb_connection_has_error() returned true May 21 14:07:34 lez volumio[794]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback May 21 14:07:34 lez volumio[794]: aplay: main:828: audio open error: Device or resource busy May 21 14:07:34 lez systemd[1]: vtcs.service: Service RestartSec=100ms expired, scheduling restart. May 21 14:07:34 lez systemd[1]: vtcs.service: Scheduled restart job, restart counter is at 3. May 21 14:07:34 lez systemd[1]: Stopped Volumio Tidal Connect Service. May 21 14:07:34 lez systemd[1]: Started Volumio Tidal Connect Service. May 21 14:07:35 lez volumio[794]: xcb_connection_has_error() returned true May 21 14:07:35 lez volumio[794]: info: QobuzConnect: setDeactiveState invoked May 21 14:07:35 lez volumio[794]: info: CoreCommandRouter::volumioGetState May 21 14:07:35 lez volumio[794]: info: CorePlayQueue::getTrack 0 May 21 14:07:35 lez volumio[794]: error: Error starting TidalConnect Cannot read property 'sendRevoke' of undefined {"stack":"TypeError: Cannot read property 'sendRevoke' of undefined\n at ControllerTidalconnect.stopTidalConnect (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:8526)\n at ControllerTidalconnect.restartService (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:8818)\n at /volumio/node_modules/v-conf/index.js:165:13\n at /volumio/node_modules/multimap/index.js:150:9\n at Array.forEach ()\n at /volumio/node_modules/multimap/index.js:149:13\n at mapEach (/volumio/node_modules/multimap/index.js:9:5)\n at Multimap.forEachEntry (/volumio/node_modules/multimap/index.js:143:5)\n at Multimap.forEach (/volumio/node_modules/multimap/index.js:148:10)\n at Config.set (/volumio/node_modules/v-conf/index.js:162:20)\n at ControllerAlsa.setSoftConf (/volumio/app/plugins/audio_interface/alsa_controller/index.js:1666:35)\n at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28)\n at processTicksAndRejections (internal/process/task_queues.js:75:11)"} May 21 14:07:35 lez volumio[794]: info: Output device has changed, restarting MPD May 21 14:07:35 lez volumio[794]: info: Output device has changed, restarting Shairport Sync May 21 14:07:35 lez sudo[4205]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 21 14:07:35 lez volumio[794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 14:07:35 lez volumio[794]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 21 14:07:36 lez sudo[4205]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 14:07:36 lez sudo[4205]: pam_unix(sudo:session): session closed for user root May 21 14:07:36 lez sudo[4207]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 21 14:07:36 lez sudo[4207]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 14:07:37 lez systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 21 14:07:37 lez systemd[1]: mpd.service: Succeeded. May 21 14:07:37 lez systemd[1]: Stopped Music Player Daemon. May 21 14:07:37 lez systemd[1]: Starting Music Player Daemon... May 21 14:07:37 lez volumio[794]: xcb_connection_has_error() returned true May 21 14:07:37 lez volumio[794]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 21 14:07:37 lez volumio[794]: xcb_connection_has_error() returned true May 21 14:07:37 lez volumio[794]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 21 14:07:37 lez volumio[794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 14:07:37 lez volumio[794]: info: QobuzConnect: setDeactiveState invoked May 21 14:07:37 lez volumio[794]: info: CoreCommandRouter::volumioGetState May 21 14:07:37 lez volumio[794]: info: CorePlayQueue::getTrack 0 May 21 14:07:37 lez volumio[794]: error: Error starting TidalConnect Cannot read property 'sendRevoke' of undefined {"stack":"TypeError: Cannot read property 'sendRevoke' of undefined\n at ControllerTidalconnect.stopTidalConnect (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:8526)\n at ControllerTidalconnect.restartService (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:8818)\n at /volumio/node_modules/v-conf/index.js:165:13\n at /volumio/node_modules/multimap/index.js:150:9\n at Array.forEach ()\n at /volumio/node_modules/multimap/index.js:149:13\n at mapEach (/volumio/node_modules/multimap/index.js:9:5)\n at Multimap.forEachEntry (/volumio/node_modules/multimap/index.js:143:5)\n at Multimap.forEach (/volumio/node_modules/multimap/index.js:148:10)\n at Config.set (/volumio/node_modules/v-conf/index.js:162:20)\n at ControllerAlsa.setSoftConf (/volumio/app/plugins/audio_interface/alsa_controller/index.js:1667:35)\n at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28)\n at processTicksAndRejections (internal/process/task_queues.js:75:11)"} May 21 14:07:38 lez sudo[4213]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 21 14:07:38 lez sudo[4213]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 14:07:38 lez systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart. May 21 14:07:38 lez systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 3. May 21 14:07:38 lez sudo[4213]: pam_unix(sudo:session): session closed for user root May 21 14:07:38 lez systemd[1]: Stopped Volumio Qobuz Connect Service. May 21 14:07:38 lez volumio[794]: xcb_connection_has_error() returned true May 21 14:07:38 lez volumio[794]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 21 14:07:38 lez volumio[794]: info: Updating Volume Controller Parameters: Device: 2 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 10 May 21 14:07:38 lez systemd[1]: Started Volumio Qobuz Connect Service. May 21 14:07:39 lez volumio[794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume May 21 14:07:39 lez go-librespot[1085]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:780:(_snd_pcm_volumioswitch_transfer_wrap) PCM volumioMultiRoomServer failed an mmap write to the the target pcm softvolume. 0 frames were written May 21 14:07:39 lez go-librespot[1085]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:818:(_snd_pcm_volumioswitch_transfer_wrap) PCM volumioMultiRoomServer has not transferred a full period to the target pcm softvolume. 0 frames were written May 21 14:07:39 lez volumio[794]: info: Disabling external Volume Control May 21 14:07:39 lez vtcs[4202]: Segmentation fault May 21 14:07:39 lez systemd[1]: vtcs.service: Main process exited, code=exited, status=139/n/a May 21 14:07:39 lez systemd[1]: vtcs.service: Failed with result 'exit-code'. May 21 14:07:39 lez systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=11/SEGV May 21 14:07:39 lez systemd[1]: qobuz-connect.service: Failed with result 'signal'. May 21 14:07:39 lez systemd[1]: vtcs.service: Service RestartSec=100ms expired, scheduling restart. May 21 14:07:39 lez systemd[1]: vtcs.service: Scheduled restart job, restart counter is at 4. May 21 14:07:39 lez systemd[1]: Stopped Volumio Tidal Connect Service. May 21 14:07:39 lez systemd[1]: Started Volumio Tidal Connect Service. May 21 14:07:41 lez sudo[4231]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 21 14:07:41 lez sudo[4231]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 14:07:41 lez sudo[4233]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 21 14:07:41 lez sudo[4233]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 14:07:41 lez systemd[1]: Stopping Volumio Tidal Connect Service... May 21 14:07:41 lez systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM May 21 14:07:41 lez systemd[1]: vtcs.service: Succeeded. May 21 14:07:41 lez systemd[1]: Stopped Volumio Tidal Connect Service. May 21 14:07:42 lez sudo[4231]: pam_unix(sudo:session): session closed for user root May 21 14:07:42 lez sudo[4236]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 21 14:07:42 lez sudo[4236]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 14:07:42 lez sudo[4233]: pam_unix(sudo:session): session closed for user root May 21 14:07:42 lez sudo[4236]: pam_unix(sudo:session): session closed for user root May 21 14:07:43 lez sudo[4245]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service May 21 14:07:43 lez sudo[4245]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 14:07:43 lez sudo[4245]: pam_unix(sudo:session): session closed for user root May 21 14:07:43 lez sudo[4252]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service May 21 14:07:43 lez sudo[4253]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service May 21 14:07:44 lez sudo[4253]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 14:07:44 lez sudo[4252]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 14:07:44 lez volumio-remote-updater[409]: [2025-05-21 14:07:44] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1006] May 21 14:07:44 lez systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart. May 21 14:07:44 lez systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 1. May 21 14:07:44 lez systemd[1]: Stopped Volumio Qobuz Connect Service. May 21 14:07:44 lez systemd[1]: Started Volumio Qobuz Connect Service. May 21 14:07:45 lez sudo[4252]: pam_unix(sudo:session): session closed for user root May 21 14:07:45 lez systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=11/SEGV May 21 14:07:45 lez systemd[1]: qobuz-connect.service: Failed with result 'signal'. May 21 14:07:45 lez systemd[1]: Stopped Volumio Qobuz Connect Service. May 21 14:07:45 lez sudo[4269]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service May 21 14:07:45 lez sudo[4269]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 14:07:45 lez sudo[4276]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service May 21 14:07:46 lez volumio[794]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 21 14:07:46 lez volumio[794]: ------------------------------------ BT MESSAGE: BT STATUS: running May 21 14:07:46 lez sudo[4276]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 14:07:46 lez volumio[794]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 21 14:07:46 lez volumio[794]: ------------------------------------ BT MESSAGE: BT STATUS: running May 21 14:07:46 lez sudo[4269]: pam_unix(sudo:session): session closed for user root May 21 14:07:46 lez volumio[794]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 21 14:07:46 lez systemd[1]: Stopped Volumio Qobuz Connect Service. May 21 14:07:46 lez volumio[794]: ------------------------------------ BT MESSAGE: BT STATUS: running May 21 14:07:46 lez volumio[794]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 21 14:07:46 lez systemd[1]: Started Volumio Qobuz Connect Service. May 21 14:07:46 lez sudo[4253]: pam_unix(sudo:session): session closed for user root May 21 14:07:46 lez volumio[794]: ------------------------------------ BT MESSAGE: BT STATUS: running May 21 14:07:47 lez sudo[4276]: pam_unix(sudo:session): session closed for user root May 21 14:07:47 lez sudo[4280]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service May 21 14:07:47 lez sudo[4280]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 14:07:47 lez systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=11/SEGV May 21 14:07:47 lez systemd[1]: qobuz-connect.service: Failed with result 'signal'. May 21 14:07:47 lez volumio[794]: ------------------------------------ BT MESSAGE: BT STATUS: running May 21 14:07:47 lez go-librespot[1085]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:912:(_snd_pcm_volumioswitch_advance) PCM volumioMultiRoomServer cannot write to target PCM softvolume as it has failed its update check. May 21 14:07:47 lez systemd[1]: Stopped Volumio Qobuz Connect Service. May 21 14:07:47 lez volumio[794]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd May 21 14:07:47 lez systemd[1]: Started Volumio Qobuz Connect Service. May 21 14:07:48 lez sudo[4280]: pam_unix(sudo:session): session closed for user root May 21 14:07:48 lez volumio[794]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf May 21 14:07:48 lez volumio[794]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 21 14:07:48 lez volumio[794]: info: Reading ALSA contributions from plugins. May 21 14:07:48 lez go-librespot[1085]: time="2025-05-21T14:07:48+02:00" level=debug msg="fetched chunk 8/10, size: 524288" uri="spotify:track:2oHWZ8kZzyHMGK0NCqdRs0" May 21 14:07:48 lez sudo[4285]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 21 14:07:48 lez sudo[4285]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 14:07:48 lez systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=11/SEGV May 21 14:07:48 lez systemd[1]: qobuz-connect.service: Failed with result 'signal'. May 21 14:07:49 lez systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 21 14:07:49 lez systemd[1]: mpd.service: Succeeded. May 21 14:07:49 lez systemd[1]: Stopped Music Player Daemon. May 21 14:07:49 lez sudo[4289]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 21 14:07:49 lez volumio[794]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 21 14:07:49 lez systemd[1]: Starting Music Player Daemon... May 21 14:07:49 lez sudo[4289]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 14:07:49 lez volumio[794]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 21 14:07:49 lez volumio[794]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 21 14:07:49 lez volumio-remote-updater[409]: [2025-05-21 14:07:49] [connect] Successful connection May 21 14:07:49 lez systemd[1]: Started Volumio Tidal Connect Service. May 21 14:07:49 lez sudo[4289]: pam_unix(sudo:session): session closed for user root May 21 14:07:50 lez volumio[794]: info: TidalConnect service stoped! May 21 14:07:50 lez volumio[794]: info: TidalConnect service stoped! May 21 14:07:50 lez sudo[4290]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 21 14:07:50 lez sudo[4290]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 14:07:50 lez sudo[4290]: pam_unix(sudo:session): session closed for user root May 21 14:07:50 lez volumio[794]: info: TidalConnect service stoped! May 21 14:07:51 lez volumio[794]: info: CoreStateMachine::pushState May 21 14:07:51 lez volumio[794]: info: CorePlayQueue::getTrack 0 May 21 14:07:51 lez volumio[794]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 14:07:51 lez volumio[794]: info: CoreCommandRouter::volumioPushState May 21 14:07:51 lez volumio[794]: info: MRS: Pushing multiroomSync output update for this device May 21 14:07:51 lez volumio[794]: info: MRS: Pushing multiroomSync output May 21 14:07:51 lez volumio[794]: info: CoreCommandRouter::volumioGetState May 21 14:07:51 lez volumio[794]: info: CorePlayQueue::getTrack 0 May 21 14:07:51 lez volumio[794]: error: Help! Some callbacks for volumioPushState are crashing! May 21 14:07:51 lez volumio[794]: error: Cannot read property 'sendVolumeMute' of undefined May 21 14:07:51 lez volumio[794]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined May 21 14:07:51 lez volumio[794]: info: MPD Permissions set May 21 14:07:51 lez volumio[794]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined May 21 14:07:51 lez volumio[794]: info: MPD Permissions set May 21 14:07:51 lez volumio[794]: info: Shairport-Sync Started May 21 14:07:52 lez volumio[794]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 21 14:07:52 lez volumio[794]: ------------------------------------ BT MESSAGE: BT STATUS: running May 21 14:07:52 lez volumio[794]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 21 14:07:52 lez volumio[794]: ------------------------------------ BT MESSAGE: BT STATUS: running May 21 14:07:52 lez go-librespot[1085]: time="2025-05-21T14:07:52+02:00" level=trace msg="received accesspoint ping" May 21 14:07:52 lez volumio[794]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 21 14:07:52 lez volumio[794]: SPOTIFY: SPOTIFY VOLUME 80 May 21 14:07:52 lez volumio[794]: SPOTIFY: VOLUMIO VOLUME 100 May 21 14:07:52 lez volumio[794]: SPOTIFY: DELTA VOLUME ENOUGH: true May 21 14:07:52 lez volumio[794]: info: Setting Spotify Volume from Volumio: 100 May 21 14:07:52 lez volumio[794]: verbose: New Socket.io Connection to 192.168.1.187 from 192.168.1.87 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 5 May 21 14:07:52 lez volumio-remote-updater[409]: [2025-05-21 14:07:52] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1747829269 101 May 21 14:07:52 lez go-librespot[1085]: time="2025-05-21T14:07:52+02:00" level=trace msg="received accesspoint pong ack" May 21 14:07:52 lez volumio[794]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 6 May 21 14:07:52 lez ntpd[530]: 82.67.41.119 local addr 192.168.1.187 -> May 21 14:07:53 lez go-librespot[1085]: time="2025-05-21T14:07:53+02:00" level=trace msg="sent dealer ping" May 21 14:07:53 lez go-librespot[1085]: time="2025-05-21T14:07:53+02:00" level=trace msg="received dealer pong" May 21 14:07:53 lez volumio[794]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 21 14:07:53 lez volumio[794]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 21 14:07:53 lez volumio[794]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 21 14:07:53 lez volumio[794]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 21 14:07:53 lez systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart. May 21 14:07:53 lez systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 1. May 21 14:07:53 lez volumio[794]: info: TidalConnect service started! May 21 14:07:53 lez volumio[794]: SPOTIFY: SETTING SPOTIFY VOLUME 100 May 21 14:07:53 lez volumio[794]: info: Sending Spotify command with payload to local API: /player/volume May 21 14:07:54 lez vtcs[4292]: Segmentation fault May 21 14:07:54 lez systemd[1]: vtcs.service: Main process exited, code=exited, status=139/n/a May 21 14:07:54 lez volumio[794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 14:07:54 lez systemd[1]: vtcs.service: Failed with result 'exit-code'. May 21 14:07:54 lez volumio[794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 14:07:54 lez volumio[794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 14:07:54 lez systemd[1]: Stopped Volumio Qobuz Connect Service. May 21 14:07:54 lez systemd[1]: Started Volumio Qobuz Connect Service. May 21 14:07:54 lez systemd[1]: vtcs.service: Service RestartSec=100ms expired, scheduling restart. May 21 14:07:54 lez systemd[1]: vtcs.service: Scheduled restart job, restart counter is at 1. May 21 14:07:54 lez volumio[794]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 21 14:07:54 lez volumio[794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 14:07:54 lez systemd[1]: Stopped Volumio Tidal Connect Service. May 21 14:07:54 lez volumio[794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 14:07:54 lez volumio[794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 14:07:54 lez systemd[1]: Started Volumio Tidal Connect Service. May 21 14:07:54 lez systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=11/SEGV May 21 14:07:55 lez systemd[1]: qobuz-connect.service: Failed with result 'signal'. May 21 14:07:55 lez volumio[794]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 21 14:07:55 lez volumio[794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 14:07:55 lez volumio[794]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 21 14:07:55 lez volumio[794]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 21 14:07:55 lez volumio[794]: error: Failed to send command to Spotify local API: /player/volume: Error: Bad Request May 21 14:07:57 lez volumio[794]: info: Starting Shairport Sync May 21 14:07:57 lez sudo[4341]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 21 14:07:57 lez sudo[4341]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 14:07:57 lez sudo[4349]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 21 14:07:57 lez sudo[4349]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 14:07:57 lez sudo[4341]: pam_unix(sudo:session): session closed for user root May 21 14:07:57 lez go-librespot[1085]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:912:(_snd_pcm_volumioswitch_advance) PCM volumioMultiRoomServer cannot write to target PCM softvolume as it has failed its update check. May 21 14:07:57 lez volumio[794]: info: Starting Shairport Sync May 21 14:07:58 lez sudo[4352]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 21 14:07:58 lez sudo[4349]: pam_unix(sudo:session): session closed for user root May 21 14:07:58 lez sudo[4352]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 14:07:58 lez volumio[794]: verbose: New Socket.io Connection to 192.168.1.187 from 192.168.1.87 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 7 May 21 14:07:59 lez vtcs[4326]: Segmentation fault May 21 14:07:59 lez systemd[1]: vtcs.service: Main process exited, code=exited, status=139/n/a May 21 14:07:59 lez systemd[1]: vtcs.service: Failed with result 'exit-code'. May 21 14:07:59 lez systemd[1]: vtcs.service: Service RestartSec=100ms expired, scheduling restart. May 21 14:07:59 lez sudo[4356]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 21 14:07:59 lez systemd[1]: vtcs.service: Scheduled restart job, restart counter is at 2. May 21 14:07:59 lez sudo[4356]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 14:07:59 lez systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 21 14:07:59 lez systemd[1]: Stopped Volumio Tidal Connect Service. May 21 14:07:59 lez systemd[1]: Started Volumio Tidal Connect Service. May 21 14:07:59 lez systemd[1]: shairport-sync.service: Succeeded. May 21 14:07:59 lez systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 21 14:08:00 lez volumio[794]: info: Asound.conf file unchanged, so no further update is needed May 21 14:08:00 lez volumio[794]: info: Output device has changed, restarting MPD May 21 14:08:00 lez systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart. May 21 14:08:00 lez systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 2. May 21 14:08:00 lez sudo[4362]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 21 14:08:00 lez sudo[4362]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 14:08:00 lez systemd[1]: Stopped Volumio Qobuz Connect Service. May 21 14:08:00 lez systemd[1]: Started Volumio Qobuz Connect Service. May 21 14:08:00 lez systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 21 14:08:00 lez go-librespot[1085]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:912:(_snd_pcm_volumioswitch_advance) PCM volumioMultiRoomServer cannot write to target PCM softvolume as it has failed its update check. May 21 14:08:00 lez sudo[4352]: pam_unix(sudo:session): session closed for user root May 21 14:08:01 lez systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=11/SEGV May 21 14:08:01 lez systemd[1]: qobuz-connect.service: Failed with result 'signal'. May 21 14:08:01 lez sudo[4368]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 21 14:08:01 lez volumio[794]: info: Output device has changed, restarting Shairport Sync May 21 14:08:01 lez volumio[794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 14:08:01 lez volumio[794]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 21 14:08:01 lez sudo[4368]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 14:08:01 lez sudo[4356]: pam_unix(sudo:session): session closed for user root May 21 14:08:02 lez sudo[4368]: pam_unix(sudo:session): session closed for user root May 21 14:08:02 lez sudo[4362]: pam_unix(sudo:session): session closed for user root May 21 14:08:02 lez sudo[4371]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 21 14:08:02 lez sudo[4371]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 14:08:02 lez go-librespot[1085]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:780:(_snd_pcm_volumioswitch_transfer_wrap) PCM volumioMultiRoomServer failed an mmap write to the the target pcm softvolume. 0 frames were written May 21 14:08:02 lez go-librespot[1085]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:818:(_snd_pcm_volumioswitch_transfer_wrap) PCM volumioMultiRoomServer has not transferred a full period to the target pcm softvolume. 0 frames were written May 21 14:08:03 lez systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 21 14:08:03 lez systemd[1]: mpd.service: Succeeded. May 21 14:08:03 lez systemd[1]: Stopped Music Player Daemon. May 21 14:08:03 lez volumio[794]: xcb_connection_has_error() returned true May 21 14:08:03 lez volumio[794]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 21 14:08:03 lez volumio[794]: xcb_connection_has_error() returned true May 21 14:08:03 lez volumio[794]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 21 14:08:03 lez systemd[1]: Starting Music Player Daemon... May 21 14:08:03 lez volumio[794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 14:08:03 lez volumio[794]: info: QobuzConnect: setDeactiveState invoked May 21 14:08:03 lez volumio[794]: info: CoreCommandRouter::volumioGetState May 21 14:08:03 lez volumio[794]: info: CorePlayQueue::getTrack 0 May 21 14:08:03 lez volumio[794]: error: Error starting TidalConnect Cannot read property 'sendRevoke' of undefined {"stack":"TypeError: Cannot read property 'sendRevoke' of undefined\n at ControllerTidalconnect.stopTidalConnect (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:8526)\n at ControllerTidalconnect.restartService (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:8818)\n at /volumio/node_modules/v-conf/index.js:165:13\n at /volumio/node_modules/multimap/index.js:150:9\n at Array.forEach ()\n at /volumio/node_modules/multimap/index.js:149:13\n at mapEach (/volumio/node_modules/multimap/index.js:9:5)\n at Multimap.forEachEntry (/volumio/node_modules/multimap/index.js:143:5)\n at Multimap.forEach (/volumio/node_modules/multimap/index.js:148:10)\n at Config.set (/volumio/node_modules/v-conf/index.js:162:20)\n at Promise._successFn (/volumio/app/plugins/audio_interface/alsa_controller/index.js:2107:39)\n at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28)\n at processTicksAndRejections (internal/process/task_queues.js:75:11)"} May 21 14:08:04 lez go-librespot[1085]: time="2025-05-21T14:08:04+02:00" level=debug msg="fetched chunk 9/10, size: 524288" uri="spotify:track:2oHWZ8kZzyHMGK0NCqdRs0" May 21 14:08:04 lez sudo[4379]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 21 14:08:04 lez sudo[4379]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 14:08:04 lez sudo[4379]: pam_unix(sudo:session): session closed for user root May 21 14:08:04 lez vtcs[4361]: Segmentation fault May 21 14:08:04 lez systemd[1]: vtcs.service: Main process exited, code=exited, status=139/n/a May 21 14:08:04 lez systemd[1]: vtcs.service: Failed with result 'exit-code'. May 21 14:08:04 lez sudo[4383]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 21 14:08:04 lez sudo[4383]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 14:08:05 lez systemd[1]: vtcs.service: Service RestartSec=100ms expired, scheduling restart. May 21 14:08:05 lez systemd[1]: vtcs.service: Scheduled restart job, restart counter is at 3. May 21 14:08:05 lez go-librespot[1085]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:780:(_snd_pcm_volumioswitch_transfer_wrap) PCM volumioMultiRoomServer failed an mmap write to the the target pcm softvolume. 0 frames were written May 21 14:08:05 lez go-librespot[1085]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:818:(_snd_pcm_volumioswitch_transfer_wrap) PCM volumioMultiRoomServer has not transferred a full period to the target pcm softvolume. 0 frames were written May 21 14:08:05 lez systemd[1]: Stopped Volumio Tidal Connect Service. May 21 14:08:05 lez systemd[1]: Started Volumio Tidal Connect Service. May 21 14:08:05 lez volumio[794]: ------------------------------------ BT MESSAGE: BT STATUS: running May 21 14:08:05 lez volumio[794]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 21 14:08:05 lez systemd[1]: Stopping Volumio Tidal Connect Service... May 21 14:08:05 lez systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM May 21 14:08:05 lez systemd[1]: vtcs.service: Succeeded. May 21 14:08:05 lez systemd[1]: Stopped Volumio Tidal Connect Service. May 21 14:08:05 lez sudo[4394]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service May 21 14:08:06 lez sudo[4383]: pam_unix(sudo:session): session closed for user root May 21 14:08:06 lez sudo[4394]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 14:08:06 lez volumio[794]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 21 14:08:06 lez volumio[794]: ------------------------------------ BT MESSAGE: BT STATUS: running May 21 14:08:06 lez volumio[794]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 21 14:08:06 lez volumio[794]: ------------------------------------ BT MESSAGE: BT STATUS: running May 21 14:08:06 lez systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart. May 21 14:08:06 lez systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 3. May 21 14:08:06 lez sudo[4394]: pam_unix(sudo:session): session closed for user root May 21 14:08:06 lez systemd[1]: Stopped Volumio Qobuz Connect Service. May 21 14:08:06 lez systemd[1]: Started Volumio Qobuz Connect Service. May 21 14:08:06 lez volumio[794]: ------------------------------------ BT MESSAGE: BT STATUS: running May 21 14:08:07 lez sudo[4397]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service May 21 14:08:07 lez sudo[4397]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 14:08:07 lez systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=11/SEGV May 21 14:08:07 lez systemd[1]: qobuz-connect.service: Failed with result 'signal'. May 21 14:08:07 lez systemd[1]: Stopped Volumio Qobuz Connect Service. May 21 14:08:07 lez volumio[794]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined May 21 14:08:07 lez systemd[1]: Started Volumio Qobuz Connect Service. May 21 14:08:07 lez volumio[794]: info: MPD Permissions set May 21 14:08:07 lez volumio[794]: info: TidalConnect service started! May 21 14:08:07 lez volumio[794]: info: Shairport-Sync Started May 21 14:08:07 lez volumio[794]: info: TidalConnect service started! May 21 14:08:07 lez volumio[794]: info: Shairport-Sync Started May 21 14:08:08 lez sudo[4397]: pam_unix(sudo:session): session closed for user root May 21 14:08:08 lez systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=11/SEGV May 21 14:08:08 lez systemd[1]: qobuz-connect.service: Failed with result 'signal'. May 21 14:08:08 lez volumio[794]: verbose: New Socket.io Connection to 192.168.1.187 from 192.168.1.87 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 8 May 21 14:08:08 lez volumio[794]: verbose: New Socket.io Connection to 192.168.1.187 from 192.168.1.87 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 9 May 21 14:08:08 lez volumio[794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 14:08:08 lez volumio[794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 14:08:08 lez volumio[794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 14:08:09 lez volumio[794]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 21 14:08:09 lez volumio[794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 14:08:09 lez volumio[794]: info: Starting Shairport Sync May 21 14:08:09 lez volumio[794]: info: TidalConnect service stoped! May 21 14:08:09 lez sudo[4420]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 21 14:08:09 lez sudo[4420]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 14:08:10 lez systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 21 14:08:10 lez systemd[1]: shairport-sync.service: Succeeded. May 21 14:08:10 lez systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 21 14:08:10 lez systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 21 14:08:10 lez sudo[4420]: pam_unix(sudo:session): session closed for user root May 21 14:08:10 lez volumio[794]: ------------------------------------ BT MESSAGE: BT STATUS: running May 21 14:08:10 lez volumio[794]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 21 14:08:10 lez volumio[794]: info: Shairport-Sync Started May 21 14:08:10 lez volumio[794]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 21 14:08:10 lez volumio[794]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 21 14:08:11 lez sudo[4438]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 21 14:08:11 lez sudo[4438]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 14:08:11 lez systemd[1]: Started Volumio Tidal Connect Service. May 21 14:08:12 lez sudo[4438]: pam_unix(sudo:session): session closed for user root May 21 14:08:13 lez volumio[794]: verbose: New Socket.io Connection to 192.168.1.187 from 192.168.1.87 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 10 May 21 14:08:13 lez volumio[794]: info: CoreCommandRouter::volumioGetState May 21 14:08:13 lez volumio[794]: info: CorePlayQueue::getTrack 0 May 21 14:08:13 lez volumio[794]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 21 14:08:13 lez systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart. May 21 14:08:13 lez systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 1. May 21 14:08:13 lez volumio[794]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom May 21 14:08:13 lez volumio[794]: info: Listing playlists May 21 14:08:13 lez volumio[794]: info: CoreCommandRouter::volumioGetVisibleSources May 21 14:08:13 lez volumio[794]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 21 14:08:13 lez volumio[794]: info: CoreCommandRouter::volumioGetQueue May 21 14:08:13 lez volumio[794]: info: CoreStateMachine::getQueue May 21 14:08:13 lez volumio[794]: info: CorePlayQueue::getQueue May 21 14:08:13 lez systemd[1]: Stopped Volumio Qobuz Connect Service. May 21 14:08:13 lez systemd[1]: Started Volumio Qobuz Connect Service. May 21 14:08:14 lez systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=11/SEGV May 21 14:08:14 lez systemd[1]: qobuz-connect.service: Failed with result 'signal'. May 21 14:08:15 lez volumio[794]: info: TidalConnect service started! May 21 14:08:16 lez vtcs[4449]: Segmentation fault May 21 14:08:16 lez systemd[1]: vtcs.service: Main process exited, code=exited, status=139/n/a May 21 14:08:16 lez systemd[1]: vtcs.service: Failed with result 'exit-code'. May 21 14:08:16 lez systemd[1]: vtcs.service: Service RestartSec=100ms expired, scheduling restart. May 21 14:08:16 lez systemd[1]: vtcs.service: Scheduled restart job, restart counter is at 1. May 21 14:08:16 lez systemd[1]: Stopped Volumio Tidal Connect Service. May 21 14:08:16 lez systemd[1]: Started Volumio Tidal Connect Service. May 21 14:08:17 lez go-librespot[1085]: time="2025-05-21T14:08:17+02:00" level=debug msg="fetched chunk 10/10, size: 196064" uri="spotify:track:2oHWZ8kZzyHMGK0NCqdRs0" May 21 14:08:19 lez systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart. May 21 14:08:19 lez systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 2. May 21 14:08:19 lez systemd[1]: Stopped Volumio Qobuz Connect Service. May 21 14:08:19 lez systemd[1]: Started Volumio Qobuz Connect Service. May 21 14:08:20 lez go-librespot[1085]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:912:(_snd_pcm_volumioswitch_advance) PCM volumioMultiRoomServer cannot write to target PCM softvolume as it has failed its update check. May 21 14:08:20 lez vtcs[4478]: Segmentation fault May 21 14:08:20 lez systemd[1]: vtcs.service: Main process exited, code=exited, status=139/n/a May 21 14:08:20 lez systemd[1]: vtcs.service: Failed with result 'exit-code'. May 21 14:08:20 lez systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=11/SEGV May 21 14:08:20 lez systemd[1]: qobuz-connect.service: Failed with result 'signal'. May 21 14:08:20 lez systemd[1]: vtcs.service: Service RestartSec=100ms expired, scheduling restart. May 21 14:08:20 lez systemd[1]: vtcs.service: Scheduled restart job, restart counter is at 2. May 21 14:08:20 lez systemd[1]: Stopped Volumio Tidal Connect Service. May 21 14:08:20 lez systemd[1]: Started Volumio Tidal Connect Service. May 21 14:08:21 lez volumio[794]: info: CoreCommandRouter::executeOnPlugin: multiroom , audioOutputPlay May 21 14:08:21 lez volumio[794]: info: Error : CoreCommandRouter::executeOnPlugin: No method [audioOutputPlay] in plugin multiroom May 21 14:08:21 lez volumio[794]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 21 14:08:21 lez volumio[794]: TypeError: Cannot read property 'then' of undefined May 21 14:08:21 lez volumio[794]: at outputs.audioOutputPlay (/volumio/app/plugins/audio_interface/outputs/index.js:367:9) May 21 14:08:21 lez volumio[794]: at CoreCommandRouter.audioOutputPlay (/volumio/app/index.js:2279:30) May 21 14:08:21 lez volumio[794]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1456:26) May 21 14:08:21 lez volumio[794]: at Socket.emit (events.js:315:20) May 21 14:08:21 lez volumio[794]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 May 21 14:08:21 lez volumio[794]: at processTicksAndRejections (internal/process/task_queues.js:75:11) May 21 14:08:21 lez volumio[794]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 21 14:08:23 lez go-librespot[1085]: time="2025-05-21T14:08:23+02:00" level=trace msg="sent dealer ping" May 21 14:08:23 lez go-librespot[1085]: time="2025-05-21T14:08:23+02:00" level=trace msg="received dealer pong" May 21 14:08:24 lez vtcs[4495]: Segmentation fault May 21 14:08:24 lez systemd[1]: vtcs.service: Main process exited, code=exited, status=139/n/a May 21 14:08:24 lez systemd[1]: vtcs.service: Failed with result 'exit-code'. May 21 14:08:24 lez systemd[1]: vtcs.service: Service RestartSec=100ms expired, scheduling restart. May 21 14:08:24 lez systemd[1]: vtcs.service: Scheduled restart job, restart counter is at 3. May 21 14:08:24 lez systemd[1]: Stopped Volumio Tidal Connect Service. May 21 14:08:24 lez systemd[1]: Started Volumio Tidal Connect Service. May 21 14:08:25 lez systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart. May 21 14:08:25 lez systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 3. May 21 14:08:25 lez systemd[1]: Stopped Volumio Qobuz Connect Service. May 21 14:08:25 lez systemd[1]: Started Volumio Qobuz Connect Service. May 21 14:08:26 lez go-librespot[1085]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:912:(_snd_pcm_volumioswitch_advance) PCM volumioMultiRoomServer cannot write to target PCM softvolume as it has failed its update check. May 21 14:08:26 lez systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=11/SEGV May 21 14:08:26 lez systemd[1]: qobuz-connect.service: Failed with result 'signal'. May 21 14:08:29 lez vtcs[4505]: Segmentation fault May 21 14:08:29 lez systemd[1]: vtcs.service: Main process exited, code=exited, status=139/n/a May 21 14:08:29 lez systemd[1]: vtcs.service: Failed with result 'exit-code'. May 21 14:08:29 lez systemd[1]: vtcs.service: Service RestartSec=100ms expired, scheduling restart. May 21 14:08:29 lez systemd[1]: vtcs.service: Scheduled restart job, restart counter is at 4. May 21 14:08:29 lez systemd[1]: Stopped Volumio Tidal Connect Service. May 21 14:08:29 lez systemd[1]: Started Volumio Tidal Connect Service. May 21 14:08:30 lez mpd[4389]: May 21 14:08 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 21 14:08:30 lez systemd[1]: Started Music Player Daemon. May 21 14:08:30 lez mpd_monitor.sh[388]: MPD Monitor Service: MPD restarted due to no mpc output. May 21 14:08:30 lez sudo[4285]: pam_unix(sudo:session): session closed for user root May 21 14:08:30 lez sudo[4171]: pam_unix(sudo:session): session closed for user root May 21 14:08:30 lez sudo[4207]: pam_unix(sudo:session): session closed for user root May 21 14:08:30 lez sudo[3990]: pam_unix(sudo:session): session closed for user root May 21 14:08:30 lez sudo[4371]: pam_unix(sudo:session): session closed for user root May 21 14:08:30 lez sudo[4088]: pam_unix(sudo:session): session closed for user root May 21 14:08:31 lez systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart. May 21 14:08:31 lez systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 4. May 21 14:08:31 lez systemd[1]: Stopped Volumio Qobuz Connect Service. May 21 14:08:31 lez systemd[1]: Started Volumio Qobuz Connect Service. May 21 14:08:32 lez systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=11/SEGV May 21 14:08:32 lez systemd[1]: qobuz-connect.service: Failed with result 'signal'. May 21 14:08:32 lez sudo[4530]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-21 14:07 May 21 14:08:32 lez sudo[4530]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 14:08:33 lez vtcs[4513]: Segmentation fault May 21 14:08:33 lez systemd[1]: vtcs.service: Main process exited, code=exited, status=139/n/a May 21 14:08:33 lez systemd[1]: vtcs.service: Failed with result 'exit-code'. 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"