Nov 23 22:36:01 bookworm-rpi5 volumio[1260]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 4 Transport: polling Total Clients: 25 Nov 23 22:36:06 bookworm-rpi5 volumio[1260]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 4 Transport: polling Total Clients: 25 Nov 23 22:36:11 bookworm-rpi5 volumio[1260]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 4 Transport: polling Total Clients: 25 Nov 23 22:36:16 bookworm-rpi5 volumio[1260]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 26 Nov 23 22:36:16 bookworm-rpi5 volumio[1260]: info: Downloading plugin at http://127.0.0.1:3000/plugin-serve/Bluetoothout.zip Nov 23 22:36:16 bookworm-rpi5 volumio[1260]: info: END DOWNLOAD: http://127.0.0.1:3000/plugin-serve/Bluetoothout.zip Nov 23 22:36:16 bookworm-rpi5 volumio[1260]: info: Folder /tmp/plugins removed Nov 23 22:36:16 bookworm-rpi5 volumio[1260]: info: Check plugin dependencies Nov 23 22:36:16 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 23 22:36:16 bookworm-rpi5 volumio[1260]: info: Checking if plugin already exists Nov 23 22:36:16 bookworm-rpi5 volumio[1260]: info: Rename folder Nov 23 22:36:16 bookworm-rpi5 volumio[1260]: info: Folder /tmp/downloaded_plugin.zip removed Nov 23 22:36:16 bookworm-rpi5 volumio[1260]: info: Move to category Nov 23 22:36:16 bookworm-rpi5 volumio[1260]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 4 Transport: polling Total Clients: 26 Nov 23 22:36:17 bookworm-rpi5 volumio[1260]: info: Checking if install.sh is present Nov 23 22:36:17 bookworm-rpi5 volumio[1260]: info: Executing install.sh Nov 23 22:36:17 bookworm-rpi5 sudo[14117]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/audio_interface/Bluetoothout/install.sh Nov 23 22:36:17 bookworm-rpi5 sudo[14117]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 22:36:17 bookworm-rpi5 sudo[14119]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/apt update Nov 23 22:36:17 bookworm-rpi5 sudo[14119]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Nov 23 22:36:17 bookworm-rpi5 volumio[1260]: info: Installing Example Plugin Dependencies Nov 23 22:36:17 bookworm-rpi5 volumio[1260]: info: Hit:1 http://archive.raspberrypi.com/debian bookworm InRelease Nov 23 22:36:17 bookworm-rpi5 volumio[1260]: info: Hit:2 http://raspbian.raspberrypi.com/raspbian bookworm InRelease Nov 23 22:36:18 bookworm-rpi5 volumio[1260]: info: Hit:3 https://deb.nodesource.com/node_20.x bookworm InRelease Nov 23 22:36:20 bookworm-rpi5 volumio[1260]: info: Reading package lists... Nov 23 22:36:20 bookworm-rpi5 volumio[1260]: info: Building dependency tree... Nov 23 22:36:20 bookworm-rpi5 volumio[1260]: info: Reading state information... Nov 23 22:36:20 bookworm-rpi5 volumio[1260]: info: All packages are up to date. Nov 23 22:36:20 bookworm-rpi5 sudo[14119]: pam_unix(sudo:session): session closed for user root Nov 23 22:36:20 bookworm-rpi5 sudo[14776]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/apt -y install --no-install-recommends bluealsa bluez bluez-tools alsa-utils pulseaudio pulseaudio-module-bluetooth Nov 23 22:36:20 bookworm-rpi5 sudo[14776]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Nov 23 22:36:21 bookworm-rpi5 volumio[1260]: info: Reading package lists... Nov 23 22:36:21 bookworm-rpi5 volumio[1260]: info: Building dependency tree... Nov 23 22:36:21 bookworm-rpi5 volumio[1260]: info: Reading state information... Nov 23 22:36:21 bookworm-rpi5 sudo[14776]: pam_unix(sudo:session): session closed for user root Nov 23 22:36:21 bookworm-rpi5 volumio[1260]: info: Package bluealsa is not available, but is referred to by another package. Nov 23 22:36:21 bookworm-rpi5 volumio[1260]: info: This may mean that the package is missing, has been obsoleted, or Nov 23 22:36:21 bookworm-rpi5 volumio[1260]: info: is only available from another source Nov 23 22:36:21 bookworm-rpi5 volumio[1260]: info: Nov 23 22:36:21 bookworm-rpi5 sudo[14865]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/systemctl daemon-reload Nov 23 22:36:21 bookworm-rpi5 sudo[14865]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Nov 23 22:36:21 bookworm-rpi5 systemd[1]: Reloading. Nov 23 22:36:22 bookworm-rpi5 systemd[1]: /lib/systemd/system/volumio-remote-updater.service:5: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Nov 23 22:36:22 bookworm-rpi5 systemd[1]: /lib/systemd/system/volumio-remote-updater.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Nov 23 22:36:22 bookworm-rpi5 volumio[1260]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 4 Transport: polling Total Clients: 26 Nov 23 22:36:22 bookworm-rpi5 systemd[1]: /lib/systemd/system/vtcs.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Nov 23 22:36:22 bookworm-rpi5 systemd[1]: /lib/systemd/system/vtcs.service:11: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Nov 23 22:36:22 bookworm-rpi5 systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Nov 23 22:36:22 bookworm-rpi5 systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Nov 23 22:36:22 bookworm-rpi5 sudo[14865]: pam_unix(sudo:session): session closed for user root Nov 23 22:36:22 bookworm-rpi5 sudo[14117]: pam_unix(sudo:session): session closed for user root Nov 23 22:36:22 bookworm-rpi5 volumio[1260]: info: Install script completed Nov 23 22:36:22 bookworm-rpi5 volumio[1260]: info: Adding reference to registry Nov 23 22:36:22 bookworm-rpi5 volumio[1260]: info: Done installing plugin. Nov 23 22:36:22 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 23 22:36:22 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 23 22:36:22 bookworm-rpi5 volumio[1260]: Plugin install end detected on script Nov 23 22:36:22 bookworm-rpi5 volumio[1260]: info: Folder /tmp/plugins removed Nov 23 22:36:22 bookworm-rpi5 volumio[1260]: info: Folder /tmp/downloaded_plugin.zip removed Nov 23 22:36:22 bookworm-rpi5 volumio[1260]: info: Folder /data/temp removed Nov 23 22:36:27 bookworm-rpi5 volumio[1260]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 4 Transport: polling Total Clients: 25 Nov 23 22:36:32 bookworm-rpi5 volumio[1260]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 4 Transport: polling Total Clients: 25 Nov 23 22:36:34 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::volumioGetState Nov 23 22:36:36 bookworm-rpi5 volumio[1260]: info: Executing endpoint metavolumio Nov 23 22:36:36 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 23 22:36:36 bookworm-rpi5 volumio[1260]: info: Executing endpoint metavolumio Nov 23 22:36:36 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 23 22:36:37 bookworm-rpi5 volumio[1260]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 4 Transport: polling Total Clients: 25 Nov 23 22:36:38 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 23 22:36:38 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 23 22:36:42 bookworm-rpi5 volumio[1260]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 4 Transport: polling Total Clients: 25 Nov 23 22:36:43 bookworm-rpi5 volumio[1260]: error: Failed request for metavolumio API Nov 23 22:36:47 bookworm-rpi5 volumio[1260]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 4 Transport: polling Total Clients: 25 Nov 23 22:36:52 bookworm-rpi5 volumio[1260]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 4 Transport: polling Total Clients: 25 Nov 23 22:36:55 bookworm-rpi5 volumio[1260]: info: Enabling plugin Bluetoothout Nov 23 22:36:55 bookworm-rpi5 volumio[1260]: info: Loading plugin "Bluetoothout"... Nov 23 22:36:55 bookworm-rpi5 volumio[1260]: info: PLUGIN START: Bluetoothout Nov 23 22:36:55 bookworm-rpi5 volumio[1260]: info: Loading i18n strings for locale fr Nov 23 22:36:55 bookworm-rpi5 volumio[1260]: info: Done. Nov 23 22:36:55 bookworm-rpi5 volumio[1260]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 26 Nov 23 22:36:55 bookworm-rpi5 sudo[19814]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd_aloop index=3 pcm_substreams=2 Nov 23 22:36:55 bookworm-rpi5 sudo[19814]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 22:36:55 bookworm-rpi5 sudo[19814]: pam_unix(sudo:session): session closed for user root Nov 23 22:36:55 bookworm-rpi5 (udev-worker)[19822]: controlC3: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 3' failed with exit code 99. Nov 23 22:36:55 bookworm-rpi5 systemd[1821]: Reached target sound.target - Sound Card. Nov 23 22:36:55 bookworm-rpi5 volumio[1260]: info: Bluetoothout--- snd_aloop loaded successfully Nov 23 22:36:57 bookworm-rpi5 volumio[1260]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 4 Transport: polling Total Clients: 26 Nov 23 22:37:02 bookworm-rpi5 volumio[1260]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 4 Transport: polling Total Clients: 26 Nov 23 22:37:03 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 23 22:37:03 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Nov 23 22:37:03 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 22:37:03 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 23 22:37:03 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 22:37:03 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 22:37:03 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 22:37:03 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 22:37:03 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 22:37:03 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 22:37:03 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 22:37:03 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Nov 23 22:37:03 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: info: Preparing to save Alsa Options, stopping services first Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::volumioGetState Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::volumioStop Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: info: CoreStateMachine::stop Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: info: CoreStateMachine::stPlaybackTimer Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: info: CoreStateMachine::updateTrackBlock Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: info: CorePlayQueue::getTrackBlock Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: info: CoreStateMachine::pushState Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: info: CorePlayQueue::getTrack 0 Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::volumioPushState Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: error: Help! Some callbacks for volumioPushState are crashing! Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: error: Cannot read properties of undefined (reading 'sendVolumeMute') Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: info: CoreStateMachine::serviceStop Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: info: CorePlayQueue::getTrack 0 Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::serviceStop Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: info: [1732397827264] ControllerWebradio::stop Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: verbose: ControllerMpd::sendMpdCommand stop Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: info: Saving Audio Output to: {"output_device":{"value":"3","label":"Loopback"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: info: Device 3 does not have any Mixer Control Available Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: error: Error starting TidalConnect Cannot read properties of undefined (reading 'sendRevoke') {"stack":"TypeError: Cannot read properties of undefined (reading 'sendRevoke')\n at ControllerTidalconnect.stopTidalConnect (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:8524)\n at ControllerTidalconnect.restartService (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:8816)\n at /volumio/node_modules/v-conf/index.js:166:13\n at /volumio/node_modules/multimap/index.js:140:9\n at Array.forEach ()\n at /volumio/node_modules/multimap/index.js:139:13\n at mapEach (/volumio/node_modules/multimap/index.js:9:5)\n at Multimap.forEachEntry (/volumio/node_modules/multimap/index.js:133:5)\n at Multimap.forEach (/volumio/node_modules/multimap/index.js:138:10)\n at Config.set (/volumio/node_modules/v-conf/index.js:163:20)\n at ControllerAlsa.setDefaultMixer (/volumio/app/plugins/audio_interface/alsa_controller/index.js:1386:43)\n at ControllerAlsa.saveAlsaOptions (/volumio/app/plugins/audio_interface/alsa_controller/index.js:840:10)\n at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1097:32)\n at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:337:42)\n at Socket.emit (node:events:514:28)\n at /volumio/node_modules/socket.io/lib/socket.js:503:12"} Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::volumioUpdateVolumeSettings Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: info: Updating Volume Controller Parameters: Device: 3 Name: Loopback Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: info: Disabling external Volume Control Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: info: CoreStateMachine::pushState Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: info: CorePlayQueue::getTrack 0 Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::volumioPushState Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: error: Help! Some callbacks for volumioPushState are crashing! Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: error: Cannot read properties of undefined (reading 'sendVolumeMute') Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: info: Preparing to generate the ALSA configuration file Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: info: Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: ---------------------------- MPD announces state update: player Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: info: sendMpdCommand stop took 249 milliseconds Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: info: ControllerMpd::getState Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: verbose: ControllerMpd::sendMpdCommand status Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: info: Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: ---------------------------- MPD announces state update: player Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: info: ControllerMpd::getState Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: verbose: ControllerMpd::sendMpdCommand status Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: info: Reading ALSA contributions from plugins. Nov 23 22:37:07 bookworm-rpi5 sudo[21572]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 23 22:37:07 bookworm-rpi5 sudo[21572]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: info: sendMpdCommand status took 2 milliseconds Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: info: sendMpdCommand status took 2 milliseconds Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: verbose: ControllerMpd::parseState Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: verbose: ControllerMpd::parseState Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 22:37:07 bookworm-rpi5 sudo[21572]: pam_unix(sudo:session): session closed for user root Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: info: sendMpdCommand playlistinfo took 41 milliseconds Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: info: sendMpdCommand playlistinfo took 42 milliseconds Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: verbose: ControllerMpd::parseTrackInfo Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: verbose: ControllerMpd::parseTrackInfo Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: info: ControllerMpd::pushState Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::servicePushState Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: info: CoreStateMachine::pushState Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: info: CorePlayQueue::getTrack 0 Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::volumioPushState Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: error: Help! Some callbacks for volumioPushState are crashing! Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: error: Cannot read properties of undefined (reading 'sendVolumeMute') Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: info: CorePlayQueue::getTrack 0 Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: info: ControllerMpd::pushState Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::servicePushState Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: info: CoreStateMachine::pushState Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: info: CorePlayQueue::getTrack 0 Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::volumioPushState Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: error: Help! Some callbacks for volumioPushState are crashing! Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: error: Cannot read properties of undefined (reading 'sendVolumeMute') Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: info: CorePlayQueue::getTrack 0 Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: info: ------------------------------ 50ms Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: info: ------------------------------ 49ms Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: info: Asound.conf file written Nov 23 22:37:07 bookworm-rpi5 sudo[21598]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Nov 23 22:37:07 bookworm-rpi5 sudo[21598]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 22:37:07 bookworm-rpi5 sudo[21598]: pam_unix(sudo:session): session closed for user root Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: alsa-lib parser.c:2783:(load_toplevel_config) Unable to find the top-level configuration file '/usr/share/alsa/ucm2/ucm.conf'. Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: alsa-lib parser.c:2783:(load_toplevel_config) Unable to find the top-level configuration file '/usr/share/alsa/ucm2/ucm.conf'. Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: alsa-lib parser.c:2783:(load_toplevel_config) Unable to find the top-level configuration file '/usr/share/alsa/ucm2/ucm.conf'. Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: No state is present for card Loopback Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: alsa-lib parser.c:2783:(load_toplevel_config) Unable to find the top-level configuration file '/usr/share/alsa/ucm2/ucm.conf'. Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: Found hardware: "Loopback" "Loopback Mixer" "" "" "" Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: Hardware is initialized using a generic method Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: No state is present for card Loopback Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: info: Output device has changed, restarting MPD Nov 23 22:37:07 bookworm-rpi5 sudo[21604]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 23 22:37:07 bookworm-rpi5 sudo[21604]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 22:37:07 bookworm-rpi5 sudo[21604]: pam_unix(sudo:session): session closed for user root Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: info: Output device has changed, restarting Shairport Sync Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 22:37:07 bookworm-rpi5 sudo[21607]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 23 22:37:07 bookworm-rpi5 sudo[21607]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 22:37:07 bookworm-rpi5 systemd[1]: Stopping mpd.service - Music Player Daemon... Nov 23 22:37:07 bookworm-rpi5 systemd[1]: mpd.service: Deactivated successfully. Nov 23 22:37:07 bookworm-rpi5 systemd[1]: Stopped mpd.service - Music Player Daemon. Nov 23 22:37:07 bookworm-rpi5 systemd[1]: mpd.socket: Deactivated successfully. Nov 23 22:37:07 bookworm-rpi5 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Nov 23 22:37:07 bookworm-rpi5 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: error: Error starting TidalConnect Cannot read properties of undefined (reading 'sendRevoke') {"stack":"TypeError: Cannot read properties of undefined (reading 'sendRevoke')\n at ControllerTidalconnect.stopTidalConnect (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:8524)\n at ControllerTidalconnect.restartService (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:8816)\n at /volumio/node_modules/v-conf/index.js:166:13\n at /volumio/node_modules/multimap/index.js:140:9\n at Array.forEach ()\n at /volumio/node_modules/multimap/index.js:139:13\n at mapEach (/volumio/node_modules/multimap/index.js:9:5)\n at Multimap.forEachEntry (/volumio/node_modules/multimap/index.js:133:5)\n at Multimap.forEach (/volumio/node_modules/multimap/index.js:138:10)\n at Config.set (/volumio/node_modules/v-conf/index.js:163: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 process.processTicksAndRejections (node:internal/process/task_queues:77:11)"} Nov 23 22:37:07 bookworm-rpi5 volumio[1260]: info: Output device has changed, restarting MPD Nov 23 22:37:07 bookworm-rpi5 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Nov 23 22:37:07 bookworm-rpi5 systemd[1]: Starting mpd.service - Music Player Daemon... Nov 23 22:37:08 bookworm-rpi5 sudo[21616]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 23 22:37:08 bookworm-rpi5 sudo[21616]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 22:37:08 bookworm-rpi5 sudo[21616]: pam_unix(sudo:session): session closed for user root Nov 23 22:37:08 bookworm-rpi5 sudo[21615]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 23 22:37:08 bookworm-rpi5 sudo[21615]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Nov 23 22:37:08 bookworm-rpi5 sudo[21615]: pam_unix(sudo:session): session closed for user root Nov 23 22:37:08 bookworm-rpi5 volumio[1260]: info: Output device has changed, restarting Shairport Sync Nov 23 22:37:08 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 22:37:08 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 22:37:08 bookworm-rpi5 sudo[21620]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 23 22:37:08 bookworm-rpi5 sudo[21620]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 22:37:08 bookworm-rpi5 systemd[1]: mpd.service: Deactivated successfully. Nov 23 22:37:08 bookworm-rpi5 systemd[1]: Stopped mpd.service - Music Player Daemon. Nov 23 22:37:08 bookworm-rpi5 systemd[1]: mpd.socket: Deactivated successfully. Nov 23 22:37:08 bookworm-rpi5 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Nov 23 22:37:08 bookworm-rpi5 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Nov 23 22:37:08 bookworm-rpi5 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Nov 23 22:37:08 bookworm-rpi5 systemd[1]: Starting mpd.service - Music Player Daemon... Nov 23 22:37:08 bookworm-rpi5 volumio[1260]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 23 22:37:08 bookworm-rpi5 volumio[1260]: error: Error starting TidalConnect Cannot read properties of undefined (reading 'sendRevoke') {"stack":"TypeError: Cannot read properties of undefined (reading 'sendRevoke')\n at ControllerTidalconnect.stopTidalConnect (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:8524)\n at ControllerTidalconnect.restartService (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:8816)\n at /volumio/node_modules/v-conf/index.js:166:13\n at /volumio/node_modules/multimap/index.js:140:9\n at Array.forEach ()\n at /volumio/node_modules/multimap/index.js:139:13\n at mapEach (/volumio/node_modules/multimap/index.js:9:5)\n at Multimap.forEachEntry (/volumio/node_modules/multimap/index.js:133:5)\n at Multimap.forEach (/volumio/node_modules/multimap/index.js:138:10)\n at Config.set (/volumio/node_modules/v-conf/index.js:163:20)\n at Promise._successFn (/volumio/app/plugins/audio_interface/alsa_controller/index.js:872:41)\n at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28)\n at process.processTicksAndRejections (node:internal/process/task_queues:77:11)"} Nov 23 22:37:08 bookworm-rpi5 sudo[21628]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 23 22:37:08 bookworm-rpi5 sudo[21628]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Nov 23 22:37:08 bookworm-rpi5 sudo[21628]: pam_unix(sudo:session): session closed for user root Nov 23 22:37:08 bookworm-rpi5 sudo[21631]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 23 22:37:08 bookworm-rpi5 sudo[21631]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 22:37:08 bookworm-rpi5 sudo[21631]: pam_unix(sudo:session): session closed for user root Nov 23 22:37:08 bookworm-rpi5 volumio[1260]: info: MPD Permissions set Nov 23 22:37:08 bookworm-rpi5 volumio[1260]: info: MPD Permissions set Nov 23 22:37:08 bookworm-rpi5 sudo[21636]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 23 22:37:08 bookworm-rpi5 sudo[21636]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 22:37:08 bookworm-rpi5 volumio[1260]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 4 Transport: polling Total Clients: 26 Nov 23 22:37:08 bookworm-rpi5 sudo[21636]: pam_unix(sudo:session): session closed for user root Nov 23 22:37:08 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 22:37:08 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 22:37:08 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 22:37:08 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 22:37:08 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 22:37:08 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 22:37:08 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 22:37:08 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 22:37:08 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 22:37:08 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 22:37:08 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 22:37:08 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 22:37:08 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 22:37:08 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 22:37:08 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 22:37:08 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 22:37:08 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 22:37:08 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 22:37:08 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 22:37:08 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 22:37:08 bookworm-rpi5 volumio[1260]: info: Starting Shairport Sync Nov 23 22:37:08 bookworm-rpi5 volumio[1260]: info: Starting Shairport Sync Nov 23 22:37:08 bookworm-rpi5 sudo[21657]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 23 22:37:08 bookworm-rpi5 sudo[21657]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 22:37:08 bookworm-rpi5 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Nov 23 22:37:08 bookworm-rpi5 systemd[1]: shairport-sync.service: Deactivated successfully. Nov 23 22:37:08 bookworm-rpi5 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Nov 23 22:37:08 bookworm-rpi5 systemd[1]: shairport-sync.service: Consumed 2.309s CPU time. Nov 23 22:37:08 bookworm-rpi5 sudo[21661]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 23 22:37:08 bookworm-rpi5 sudo[21661]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 22:37:08 bookworm-rpi5 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Nov 23 22:37:08 bookworm-rpi5 sudo[21657]: pam_unix(sudo:session): session closed for user root Nov 23 22:37:08 bookworm-rpi5 volumio[1260]: info: Shairport-Sync Started Nov 23 22:37:08 bookworm-rpi5 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Nov 23 22:37:08 bookworm-rpi5 systemd[1]: shairport-sync.service: Deactivated successfully. Nov 23 22:37:08 bookworm-rpi5 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Nov 23 22:37:08 bookworm-rpi5 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Nov 23 22:37:08 bookworm-rpi5 sudo[21661]: pam_unix(sudo:session): session closed for user root Nov 23 22:37:08 bookworm-rpi5 volumio[1260]: info: Shairport-Sync Started Nov 23 22:37:08 bookworm-rpi5 mpd[21632]: Nov 23 22:37 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 23 22:37:08 bookworm-rpi5 systemd[1]: Started mpd.service - Music Player Daemon. Nov 23 22:37:08 bookworm-rpi5 sudo[21620]: pam_unix(sudo:session): session closed for user root Nov 23 22:37:08 bookworm-rpi5 volumio[1260]: error: updateQueue error: null Nov 23 22:37:08 bookworm-rpi5 sudo[21607]: pam_unix(sudo:session): session closed for user root Nov 23 22:37:08 bookworm-rpi5 volumio[1260]: error: updateQueue error: null Nov 23 22:37:10 bookworm-rpi5 volumio[1260]: info: TidalConnect service stoped! Nov 23 22:37:10 bookworm-rpi5 volumio[1260]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Nov 23 22:37:10 bookworm-rpi5 volumio[1260]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Nov 23 22:37:10 bookworm-rpi5 sudo[21697]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Nov 23 22:37:10 bookworm-rpi5 sudo[21697]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 22:37:10 bookworm-rpi5 systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Nov 23 22:37:10 bookworm-rpi5 vtcs[21700]: /usr/bin/vtcs: error while loading shared libraries: libssl.so.1.1: cannot open shared object file: No such file or directory Nov 23 22:37:10 bookworm-rpi5 sudo[21697]: pam_unix(sudo:session): session closed for user root Nov 23 22:37:10 bookworm-rpi5 systemd[1]: vtcs.service: Main process exited, code=exited, status=127/n/a Nov 23 22:37:10 bookworm-rpi5 systemd[1]: vtcs.service: Failed with result 'exit-code'. Nov 23 22:37:10 bookworm-rpi5 systemd[1]: vtcs.service: Scheduled restart job, restart counter is at 1. Nov 23 22:37:10 bookworm-rpi5 systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Nov 23 22:37:10 bookworm-rpi5 systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Nov 23 22:37:10 bookworm-rpi5 vtcs[21703]: /usr/bin/vtcs: error while loading shared libraries: libssl.so.1.1: cannot open shared object file: No such file or directory Nov 23 22:37:10 bookworm-rpi5 systemd[1]: vtcs.service: Main process exited, code=exited, status=127/n/a Nov 23 22:37:10 bookworm-rpi5 systemd[1]: vtcs.service: Failed with result 'exit-code'. Nov 23 22:37:11 bookworm-rpi5 systemd[1]: vtcs.service: Scheduled restart job, restart counter is at 2. Nov 23 22:37:11 bookworm-rpi5 systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Nov 23 22:37:11 bookworm-rpi5 systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Nov 23 22:37:11 bookworm-rpi5 vtcs[21705]: /usr/bin/vtcs: error while loading shared libraries: libssl.so.1.1: cannot open shared object file: No such file or directory Nov 23 22:37:11 bookworm-rpi5 systemd[1]: vtcs.service: Main process exited, code=exited, status=127/n/a Nov 23 22:37:11 bookworm-rpi5 systemd[1]: vtcs.service: Failed with result 'exit-code'. Nov 23 22:37:11 bookworm-rpi5 volumio[1260]: info: TidalConnect service stoped! Nov 23 22:37:11 bookworm-rpi5 volumio[1260]: info: TidalConnect service stoped! Nov 23 22:37:11 bookworm-rpi5 volumio[1260]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Nov 23 22:37:11 bookworm-rpi5 volumio[1260]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Nov 23 22:37:11 bookworm-rpi5 systemd[1]: vtcs.service: Scheduled restart job, restart counter is at 3. Nov 23 22:37:11 bookworm-rpi5 systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Nov 23 22:37:11 bookworm-rpi5 volumio[1260]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Nov 23 22:37:11 bookworm-rpi5 volumio[1260]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Nov 23 22:37:11 bookworm-rpi5 sudo[21724]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Nov 23 22:37:11 bookworm-rpi5 sudo[21724]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 22:37:11 bookworm-rpi5 systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Nov 23 22:37:11 bookworm-rpi5 sudo[21727]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Nov 23 22:37:11 bookworm-rpi5 sudo[21727]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 22:37:11 bookworm-rpi5 vtcs[21729]: /usr/bin/vtcs: error while loading shared libraries: libssl.so.1.1: cannot open shared object file: No such file or directory Nov 23 22:37:11 bookworm-rpi5 systemd[1]: vtcs.service: Main process exited, code=exited, status=127/n/a Nov 23 22:37:11 bookworm-rpi5 systemd[1]: vtcs.service: Failed with result 'exit-code'. Nov 23 22:37:11 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Nov 23 22:37:11 bookworm-rpi5 volumio[1260]: info: CoreStateMachine::getcurrentVolume Nov 23 22:37:11 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::volumioRetrievevolume Nov 23 22:37:11 bookworm-rpi5 volumio[1260]: info: CoreStateMachine::pushState Nov 23 22:37:11 bookworm-rpi5 volumio[1260]: info: CorePlayQueue::getTrack 0 Nov 23 22:37:11 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::volumioPushState Nov 23 22:37:11 bookworm-rpi5 volumio[1260]: error: Help! Some callbacks for volumioPushState are crashing! Nov 23 22:37:11 bookworm-rpi5 volumio[1260]: error: Cannot read properties of undefined (reading 'sendVolumeMute') Nov 23 22:37:11 bookworm-rpi5 systemd[1]: vtcs.service: Scheduled restart job, restart counter is at 4. Nov 23 22:37:11 bookworm-rpi5 systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Nov 23 22:37:11 bookworm-rpi5 systemd[1]: vtcs.service: Start request repeated too quickly. Nov 23 22:37:11 bookworm-rpi5 systemd[1]: vtcs.service: Failed with result 'exit-code'. Nov 23 22:37:11 bookworm-rpi5 systemd[1]: Failed to start vtcs.service - Volumio Tidal Connect Service. Nov 23 22:37:11 bookworm-rpi5 sudo[21727]: pam_unix(sudo:session): session closed for user root Nov 23 22:37:11 bookworm-rpi5 sudo[21724]: pam_unix(sudo:session): session closed for user root Nov 23 22:37:11 bookworm-rpi5 volumio[1260]: error: Error starting TidalConnect Command failed: /usr/bin/sudo /bin/systemctl start vtcs.service && sleep 3 Nov 23 22:37:11 bookworm-rpi5 volumio[1260]: Job for vtcs.service failed because the control process exited with error code. Nov 23 22:37:11 bookworm-rpi5 volumio[1260]: See "systemctl status vtcs.service" and "journalctl -xeu vtcs.service" for details. Nov 23 22:37:11 bookworm-rpi5 volumio[1260]: {"cmd":"/usr/bin/sudo /bin/systemctl start vtcs.service && sleep 3","code":1,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/bin/sudo /bin/systemctl start vtcs.service && sleep 3\nJob for vtcs.service failed because the control process exited with error code.\nSee \"systemctl status vtcs.service\" and \"journalctl -xeu vtcs.service\" for details.\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at ChildProcess._handle.onexit (node:internal/child_process:305:5)","stderr":"Job for vtcs.service failed because the control process exited with error code.\nSee \"systemctl status vtcs.service\" and \"journalctl -xeu vtcs.service\" for details.\n","stdout":""} Nov 23 22:37:11 bookworm-rpi5 volumio[1260]: error: Error starting TidalConnect Command failed: /usr/bin/sudo /bin/systemctl start vtcs.service && sleep 3 Nov 23 22:37:11 bookworm-rpi5 volumio[1260]: Job for vtcs.service failed because the control process exited with error code. Nov 23 22:37:11 bookworm-rpi5 volumio[1260]: See "systemctl status vtcs.service" and "journalctl -xeu vtcs.service" for details. Nov 23 22:37:11 bookworm-rpi5 volumio[1260]: {"cmd":"/usr/bin/sudo /bin/systemctl start vtcs.service && sleep 3","code":1,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/bin/sudo /bin/systemctl start vtcs.service && sleep 3\nJob for vtcs.service failed because the control process exited with error code.\nSee \"systemctl status vtcs.service\" and \"journalctl -xeu vtcs.service\" for details.\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)","stderr":"Job for vtcs.service failed because the control process exited with error code.\nSee \"systemctl status vtcs.service\" and \"journalctl -xeu vtcs.service\" for details.\n","stdout":""} Nov 23 22:37:13 bookworm-rpi5 volumio[1260]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 4 Transport: polling Total Clients: 26 Nov 23 22:37:13 bookworm-rpi5 volumio[1260]: info: TidalConnect service started! Nov 23 22:37:18 bookworm-rpi5 volumio[1260]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 4 Transport: polling Total Clients: 26 Nov 23 22:37:23 bookworm-rpi5 volumio[1260]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 4 Transport: polling Total Clients: 26 Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::volumioGetState Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: info: CorePlayQueue::getTrack 0 Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: info: Restoring Previous Volume level: 100 false true Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: info: VolumeController::SetAlsaVolume100 Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: info: Enable softmixer device for audio device number 3 Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::volumioStop Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: info: CoreStateMachine::stop Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: info: Enable softmixer device for audio device undefined Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::volumioUpdateVolumeSettings Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: info: Updating Volume Controller Parameters: Device: 3 Name: Loopback Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: info: Disabling external Volume Control Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: info: Output device has changed, restarting MPD Nov 23 22:37:25 bookworm-rpi5 sudo[21765]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 23 22:37:25 bookworm-rpi5 sudo[21765]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 22:37:25 bookworm-rpi5 sudo[21765]: pam_unix(sudo:session): session closed for user root Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: info: Output device has changed, restarting Shairport Sync Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 22:37:25 bookworm-rpi5 sudo[21768]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 23 22:37:25 bookworm-rpi5 sudo[21768]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 22:37:25 bookworm-rpi5 systemd[1]: Stopping mpd.service - Music Player Daemon... Nov 23 22:37:25 bookworm-rpi5 systemd[1]: mpd.service: Deactivated successfully. Nov 23 22:37:25 bookworm-rpi5 systemd[1]: Stopped mpd.service - Music Player Daemon. Nov 23 22:37:25 bookworm-rpi5 systemd[1]: mpd.socket: Deactivated successfully. Nov 23 22:37:25 bookworm-rpi5 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Nov 23 22:37:25 bookworm-rpi5 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: error: Error starting TidalConnect Cannot read properties of undefined (reading 'sendRevoke') {"stack":"TypeError: Cannot read properties of undefined (reading 'sendRevoke')\n at ControllerTidalconnect.stopTidalConnect (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:8524)\n at ControllerTidalconnect.restartService (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:8816)\n at /volumio/node_modules/v-conf/index.js:166:13\n at /volumio/node_modules/multimap/index.js:140:9\n at Array.forEach ()\n at /volumio/node_modules/multimap/index.js:139:13\n at mapEach (/volumio/node_modules/multimap/index.js:9:5)\n at Multimap.forEachEntry (/volumio/node_modules/multimap/index.js:133:5)\n at Multimap.forEach (/volumio/node_modules/multimap/index.js:138:10)\n at Config.set (/volumio/node_modules/v-conf/index.js:163:20)\n at ControllerAlsa.saveVolumeOptions (/volumio/app/plugins/audio_interface/alsa_controller/index.js:942:39)\n at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1097:32)\n at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:337:42)\n at Socket.emit (node:events:514:28)\n at /volumio/node_modules/socket.io/lib/socket.js:503:12\n at process.processTicksAndRejections (node:internal/process/task_queues:77:11)"} Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: info: Volume configurations have been set Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: error: Error starting TidalConnect Cannot read properties of undefined (reading 'sendRevoke') {"stack":"TypeError: Cannot read properties of undefined (reading 'sendRevoke')\n at ControllerTidalconnect.stopTidalConnect (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:8524)\n at ControllerTidalconnect.restartService (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:8816)\n at /volumio/node_modules/v-conf/index.js:166:13\n at /volumio/node_modules/multimap/index.js:140:9\n at Array.forEach ()\n at /volumio/node_modules/multimap/index.js:139:13\n at mapEach (/volumio/node_modules/multimap/index.js:9:5)\n at Multimap.forEachEntry (/volumio/node_modules/multimap/index.js:133:5)\n at Multimap.forEach (/volumio/node_modules/multimap/index.js:138:10)\n at Config.set (/volumio/node_modules/v-conf/index.js:163:20)\n at ControllerAlsa.saveVolumeOptions (/volumio/app/plugins/audio_interface/alsa_controller/index.js:983:33)\n at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1097:32)\n at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:337:42)\n at Socket.emit (node:events:514:28)\n at /volumio/node_modules/socket.io/lib/socket.js:503:12\n at process.processTicksAndRejections (node:internal/process/task_queues:77:11)"} Nov 23 22:37:25 bookworm-rpi5 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Nov 23 22:37:25 bookworm-rpi5 systemd[1]: Starting mpd.service - Music Player Daemon... Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::volumioUpdateVolumeSettings Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: info: Updating Volume Controller Parameters: Device: 3 Name: Loopback Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: info: Disabling external Volume Control Nov 23 22:37:25 bookworm-rpi5 sudo[21777]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 23 22:37:25 bookworm-rpi5 sudo[21777]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Nov 23 22:37:25 bookworm-rpi5 sudo[21777]: pam_unix(sudo:session): session closed for user root Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: info: CoreStateMachine::pushState Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: info: CorePlayQueue::getTrack 0 Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::volumioPushState Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: error: Help! Some callbacks for volumioPushState are crashing! Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: error: Cannot read properties of undefined (reading 'sendVolumeMute') Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: info: CoreStateMachine::pushState Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: info: CorePlayQueue::getTrack 0 Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::volumioPushState Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: error: Help! Some callbacks for volumioPushState are crashing! Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: error: Cannot read properties of undefined (reading 'sendVolumeMute') Nov 23 22:37:25 bookworm-rpi5 sudo[21789]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 23 22:37:25 bookworm-rpi5 sudo[21789]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 22:37:25 bookworm-rpi5 sudo[21789]: pam_unix(sudo:session): session closed for user root Nov 23 22:37:25 bookworm-rpi5 sudo[21793]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 23 22:37:25 bookworm-rpi5 sudo[21793]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: info: CoreStateMachine::pushState Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: info: CorePlayQueue::getTrack 0 Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::volumioPushState Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: error: Help! Some callbacks for volumioPushState are crashing! Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: error: Cannot read properties of undefined (reading 'sendVolumeMute') Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: info: MPD Permissions set Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: info: Software Volume ALSA configuration written Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: info: Preparing to generate the ALSA configuration file Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 22:37:25 bookworm-rpi5 sudo[21793]: pam_unix(sudo:session): session closed for user root Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: info: Reading ALSA contributions from plugins. Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: info: Starting Shairport Sync Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: info: VolumeController::SetAlsaVolume0 Nov 23 22:37:25 bookworm-rpi5 sudo[21808]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 23 22:37:25 bookworm-rpi5 sudo[21808]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 22:37:25 bookworm-rpi5 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Nov 23 22:37:25 bookworm-rpi5 systemd[1]: shairport-sync.service: Deactivated successfully. Nov 23 22:37:25 bookworm-rpi5 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Nov 23 22:37:25 bookworm-rpi5 systemd[1]: shairport-sync.service: Consumed 1.536s CPU time. Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: info: CoreStateMachine::pushState Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: info: CorePlayQueue::getTrack 0 Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::volumioPushState Nov 23 22:37:25 bookworm-rpi5 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Nov 23 22:37:25 bookworm-rpi5 sudo[21808]: pam_unix(sudo:session): session closed for user root Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: error: Help! Some callbacks for volumioPushState are crashing! Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: error: Cannot read properties of undefined (reading 'sendVolumeMute') Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: info: Shairport-Sync Started Nov 23 22:37:25 bookworm-rpi5 volumio[1260]: info: Asound.conf file written Nov 23 22:37:26 bookworm-rpi5 sudo[21828]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Nov 23 22:37:26 bookworm-rpi5 sudo[21828]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 22:37:26 bookworm-rpi5 sudo[21828]: pam_unix(sudo:session): session closed for user root Nov 23 22:37:26 bookworm-rpi5 mpd[21781]: Nov 23 22:37 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 23 22:37:26 bookworm-rpi5 systemd[1]: Started mpd.service - Music Player Daemon. Nov 23 22:37:26 bookworm-rpi5 sudo[21768]: pam_unix(sudo:session): session closed for user root Nov 23 22:37:26 bookworm-rpi5 volumio[1260]: alsa-lib parser.c:2783:(load_toplevel_config) Unable to find the top-level configuration file '/usr/share/alsa/ucm2/ucm.conf'. Nov 23 22:37:26 bookworm-rpi5 volumio[1260]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Nov 23 22:37:26 bookworm-rpi5 volumio[1260]: alsa-lib parser.c:2783:(load_toplevel_config) Unable to find the top-level configuration file '/usr/share/alsa/ucm2/ucm.conf'. Nov 23 22:37:26 bookworm-rpi5 volumio[1260]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Nov 23 22:37:26 bookworm-rpi5 volumio[1260]: alsa-lib parser.c:2783:(load_toplevel_config) Unable to find the top-level configuration file '/usr/share/alsa/ucm2/ucm.conf'. Nov 23 22:37:26 bookworm-rpi5 volumio[1260]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 Nov 23 22:37:26 bookworm-rpi5 volumio[1260]: No state is present for card Loopback Nov 23 22:37:26 bookworm-rpi5 volumio[1260]: alsa-lib parser.c:2783:(load_toplevel_config) Unable to find the top-level configuration file '/usr/share/alsa/ucm2/ucm.conf'. Nov 23 22:37:26 bookworm-rpi5 volumio[1260]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 Nov 23 22:37:26 bookworm-rpi5 volumio[1260]: Found hardware: "Loopback" "Loopback Mixer" "" "" "" Nov 23 22:37:26 bookworm-rpi5 volumio[1260]: Hardware is initialized using a generic method Nov 23 22:37:26 bookworm-rpi5 volumio[1260]: No state is present for card Loopback Nov 23 22:37:26 bookworm-rpi5 volumio[1260]: info: Output device has changed, restarting MPD Nov 23 22:37:26 bookworm-rpi5 sudo[21836]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 23 22:37:26 bookworm-rpi5 sudo[21836]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 22:37:26 bookworm-rpi5 sudo[21836]: pam_unix(sudo:session): session closed for user root Nov 23 22:37:26 bookworm-rpi5 volumio[1260]: info: Output device has changed, restarting Shairport Sync Nov 23 22:37:26 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 22:37:26 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 22:37:26 bookworm-rpi5 sudo[21840]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 23 22:37:26 bookworm-rpi5 sudo[21840]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 22:37:26 bookworm-rpi5 systemd[1]: Stopping mpd.service - Music Player Daemon... Nov 23 22:37:26 bookworm-rpi5 systemd[1]: mpd.service: Deactivated successfully. Nov 23 22:37:26 bookworm-rpi5 systemd[1]: Stopped mpd.service - Music Player Daemon. Nov 23 22:37:26 bookworm-rpi5 systemd[1]: mpd.socket: Deactivated successfully. Nov 23 22:37:26 bookworm-rpi5 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Nov 23 22:37:26 bookworm-rpi5 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Nov 23 22:37:26 bookworm-rpi5 volumio[1260]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 23 22:37:26 bookworm-rpi5 volumio[1260]: error: Error starting TidalConnect Cannot read properties of undefined (reading 'sendRevoke') {"stack":"TypeError: Cannot read properties of undefined (reading 'sendRevoke')\n at ControllerTidalconnect.stopTidalConnect (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:8524)\n at ControllerTidalconnect.restartService (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:8816)\n at /volumio/node_modules/v-conf/index.js:166:13\n at /volumio/node_modules/multimap/index.js:140:9\n at Array.forEach ()\n at /volumio/node_modules/multimap/index.js:139:13\n at mapEach (/volumio/node_modules/multimap/index.js:9:5)\n at Multimap.forEachEntry (/volumio/node_modules/multimap/index.js:133:5)\n at Multimap.forEach (/volumio/node_modules/multimap/index.js:138:10)\n at Config.set (/volumio/node_modules/v-conf/index.js:163: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 process.processTicksAndRejections (node:internal/process/task_queues:77:11)"} Nov 23 22:37:26 bookworm-rpi5 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Nov 23 22:37:26 bookworm-rpi5 systemd[1]: Starting mpd.service - Music Player Daemon... Nov 23 22:37:26 bookworm-rpi5 sudo[21848]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 23 22:37:26 bookworm-rpi5 sudo[21848]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Nov 23 22:37:26 bookworm-rpi5 sudo[21848]: pam_unix(sudo:session): session closed for user root Nov 23 22:37:26 bookworm-rpi5 mpd[21853]: Nov 23 22:37 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 23 22:37:26 bookworm-rpi5 sudo[21840]: pam_unix(sudo:session): session closed for user root Nov 23 22:37:26 bookworm-rpi5 systemd[1]: Started mpd.service - Music Player Daemon. Nov 23 22:37:26 bookworm-rpi5 volumio[1260]: Playing WAVE '/volumio/app/silence.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Nov 23 22:37:26 bookworm-rpi5 volumio[1260]: alsa-lib parser.c:2783:(load_toplevel_config) Unable to find the top-level configuration file '/usr/share/alsa/ucm2/ucm.conf'. Nov 23 22:37:26 bookworm-rpi5 volumio[1260]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Nov 23 22:37:26 bookworm-rpi5 volumio[1260]: alsa-lib parser.c:2783:(load_toplevel_config) Unable to find the top-level configuration file '/usr/share/alsa/ucm2/ucm.conf'. Nov 23 22:37:26 bookworm-rpi5 volumio[1260]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Nov 23 22:37:26 bookworm-rpi5 volumio[1260]: alsa-lib parser.c:2783:(load_toplevel_config) Unable to find the top-level configuration file '/usr/share/alsa/ucm2/ucm.conf'. Nov 23 22:37:26 bookworm-rpi5 volumio[1260]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 Nov 23 22:37:26 bookworm-rpi5 volumio[1260]: No state is present for card Loopback Nov 23 22:37:26 bookworm-rpi5 volumio[1260]: alsa-lib parser.c:2783:(load_toplevel_config) Unable to find the top-level configuration file '/usr/share/alsa/ucm2/ucm.conf'. Nov 23 22:37:26 bookworm-rpi5 volumio[1260]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 Nov 23 22:37:26 bookworm-rpi5 volumio[1260]: Found hardware: "Loopback" "Loopback Mixer" "" "" "" Nov 23 22:37:26 bookworm-rpi5 volumio[1260]: Hardware is initialized using a generic method Nov 23 22:37:26 bookworm-rpi5 volumio[1260]: No state is present for card Loopback Nov 23 22:37:26 bookworm-rpi5 volumio[1260]: error: Error starting TidalConnect Cannot read properties of undefined (reading 'sendRevoke') {"stack":"TypeError: Cannot read properties of undefined (reading 'sendRevoke')\n at ControllerTidalconnect.stopTidalConnect (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:8524)\n at ControllerTidalconnect.restartService (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:8816)\n at /volumio/node_modules/v-conf/index.js:166:13\n at /volumio/node_modules/multimap/index.js:140:9\n at Array.forEach ()\n at /volumio/node_modules/multimap/index.js:139:13\n at mapEach (/volumio/node_modules/multimap/index.js:9:5)\n at Multimap.forEachEntry (/volumio/node_modules/multimap/index.js:133:5)\n at Multimap.forEach (/volumio/node_modules/multimap/index.js:138:10)\n at Config.set (/volumio/node_modules/v-conf/index.js:163: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 process.processTicksAndRejections (node:internal/process/task_queues:77:11)"} Nov 23 22:37:26 bookworm-rpi5 volumio[1260]: info: Output device has changed, restarting MPD Nov 23 22:37:26 bookworm-rpi5 sudo[21860]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 23 22:37:26 bookworm-rpi5 sudo[21860]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 22:37:26 bookworm-rpi5 sudo[21860]: pam_unix(sudo:session): session closed for user root Nov 23 22:37:26 bookworm-rpi5 volumio[1260]: info: Output device has changed, restarting Shairport Sync Nov 23 22:37:26 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 22:37:26 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 22:37:26 bookworm-rpi5 sudo[21863]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 23 22:37:26 bookworm-rpi5 sudo[21863]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 22:37:26 bookworm-rpi5 systemd[1]: Stopping mpd.service - Music Player Daemon... Nov 23 22:37:27 bookworm-rpi5 systemd[1]: mpd.service: Deactivated successfully. Nov 23 22:37:27 bookworm-rpi5 volumio[1260]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 23 22:37:27 bookworm-rpi5 systemd[1]: Stopped mpd.service - Music Player Daemon. Nov 23 22:37:27 bookworm-rpi5 systemd[1]: mpd.socket: Deactivated successfully. Nov 23 22:37:27 bookworm-rpi5 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Nov 23 22:37:27 bookworm-rpi5 volumio[1260]: error: Error starting TidalConnect Cannot read properties of undefined (reading 'sendRevoke') {"stack":"TypeError: Cannot read properties of undefined (reading 'sendRevoke')\n at ControllerTidalconnect.stopTidalConnect (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:8524)\n at ControllerTidalconnect.restartService (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:8816)\n at /volumio/node_modules/v-conf/index.js:166:13\n at /volumio/node_modules/multimap/index.js:140:9\n at Array.forEach ()\n at /volumio/node_modules/multimap/index.js:139:13\n at mapEach (/volumio/node_modules/multimap/index.js:9:5)\n at Multimap.forEachEntry (/volumio/node_modules/multimap/index.js:133:5)\n at Multimap.forEach (/volumio/node_modules/multimap/index.js:138:10)\n at Config.set (/volumio/node_modules/v-conf/index.js:163: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 process.processTicksAndRejections (node:internal/process/task_queues:77:11)"} Nov 23 22:37:27 bookworm-rpi5 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Nov 23 22:37:27 bookworm-rpi5 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Nov 23 22:37:27 bookworm-rpi5 systemd[1]: Starting mpd.service - Music Player Daemon... Nov 23 22:37:27 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::volumioUpdateVolumeSettings Nov 23 22:37:27 bookworm-rpi5 volumio[1260]: info: Updating Volume Controller Parameters: Device: 3 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Nov 23 22:37:27 bookworm-rpi5 sudo[21872]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 23 22:37:27 bookworm-rpi5 sudo[21872]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Nov 23 22:37:27 bookworm-rpi5 sudo[21872]: pam_unix(sudo:session): session closed for user root Nov 23 22:37:27 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Nov 23 22:37:27 bookworm-rpi5 volumio[1260]: info: Disabling external Volume Control Nov 23 22:37:27 bookworm-rpi5 sudo[21884]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 23 22:37:27 bookworm-rpi5 sudo[21884]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 22:37:27 bookworm-rpi5 sudo[21884]: pam_unix(sudo:session): session closed for user root Nov 23 22:37:27 bookworm-rpi5 sudo[21888]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 23 22:37:27 bookworm-rpi5 sudo[21888]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 22:37:27 bookworm-rpi5 sudo[21888]: pam_unix(sudo:session): session closed for user root Nov 23 22:37:27 bookworm-rpi5 volumio[1260]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Nov 23 22:37:27 bookworm-rpi5 volumio[1260]: info: FusionDsp - ---- read samplerate from file: 44100 Nov 23 22:37:27 bookworm-rpi5 volumio[1260]: info: camilladsp stopping service pid 21639... Nov 23 22:37:27 bookworm-rpi5 sudo[21892]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 23 22:37:27 bookworm-rpi5 sudo[21892]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 22:37:27 bookworm-rpi5 sudo[21892]: pam_unix(sudo:session): session closed for user root Nov 23 22:37:27 bookworm-rpi5 volumio[1260]: info: camilladsp service terminated, instance 1 Nov 23 22:37:27 bookworm-rpi5 volumio[1260]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 23 22:37:27 bookworm-rpi5 volumio[1260]: info: camilladsp service started and running in background, instance 1 Nov 23 22:37:27 bookworm-rpi5 volumio[1260]: info: CoreStateMachine::pushState Nov 23 22:37:27 bookworm-rpi5 volumio[1260]: info: CorePlayQueue::getTrack 0 Nov 23 22:37:27 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 22:37:27 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::volumioPushState Nov 23 22:37:27 bookworm-rpi5 volumio[1260]: error: Help! Some callbacks for volumioPushState are crashing! Nov 23 22:37:27 bookworm-rpi5 volumio[1260]: error: Cannot read properties of undefined (reading 'sendVolumeMute') Nov 23 22:37:27 bookworm-rpi5 volumio[1260]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Nov 23 22:37:27 bookworm-rpi5 volumio[1260]: info: MPD Permissions set Nov 23 22:37:27 bookworm-rpi5 volumio[1260]: info: MPD Permissions set Nov 23 22:37:27 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 22:37:27 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 22:37:27 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 22:37:27 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 22:37:27 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 22:37:27 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 22:37:27 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 22:37:27 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 22:37:27 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 22:37:27 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 22:37:27 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 22:37:27 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 22:37:27 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 22:37:27 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 22:37:27 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 22:37:27 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 22:37:27 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 22:37:27 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 22:37:27 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 22:37:27 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 22:37:27 bookworm-rpi5 volumio[1260]: info: Starting Shairport Sync Nov 23 22:37:27 bookworm-rpi5 volumio[1260]: info: Starting Shairport Sync Nov 23 22:37:27 bookworm-rpi5 sudo[21916]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 23 22:37:27 bookworm-rpi5 sudo[21916]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 22:37:27 bookworm-rpi5 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Nov 23 22:37:27 bookworm-rpi5 systemd[1]: shairport-sync.service: Deactivated successfully. Nov 23 22:37:27 bookworm-rpi5 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Nov 23 22:37:27 bookworm-rpi5 systemd[1]: shairport-sync.service: Consumed 1.506s CPU time. Nov 23 22:37:27 bookworm-rpi5 sudo[21920]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 23 22:37:27 bookworm-rpi5 sudo[21920]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 22:37:27 bookworm-rpi5 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Nov 23 22:37:27 bookworm-rpi5 sudo[21916]: pam_unix(sudo:session): session closed for user root Nov 23 22:37:27 bookworm-rpi5 volumio[1260]: info: Shairport-Sync Started Nov 23 22:37:27 bookworm-rpi5 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Nov 23 22:37:27 bookworm-rpi5 systemd[1]: shairport-sync.service: Deactivated successfully. Nov 23 22:37:27 bookworm-rpi5 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Nov 23 22:37:27 bookworm-rpi5 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Nov 23 22:37:27 bookworm-rpi5 sudo[21920]: pam_unix(sudo:session): session closed for user root Nov 23 22:37:27 bookworm-rpi5 volumio[1260]: info: Shairport-Sync Started Nov 23 22:37:27 bookworm-rpi5 volumio[1260]: (node:1260) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pbeg listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Nov 23 22:37:27 bookworm-rpi5 volumio[1260]: (Use `node --trace-warnings ...` to show where the warning was created) Nov 23 22:37:27 bookworm-rpi5 volumio[1260]: (node:1260) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 meta listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Nov 23 22:37:27 bookworm-rpi5 volumio[1260]: (node:1260) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 prgr listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Nov 23 22:37:27 bookworm-rpi5 volumio[1260]: (node:1260) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pvol listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Nov 23 22:37:27 bookworm-rpi5 volumio[1260]: (node:1260) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pend listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Nov 23 22:37:27 bookworm-rpi5 mpd[21876]: Nov 23 22:37 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 23 22:37:27 bookworm-rpi5 volumio[1260]: error: MPD error: The expression evaluated to a falsy value: Nov 23 22:37:27 bookworm-rpi5 volumio[1260]: assert.ok(self.idling) Nov 23 22:37:27 bookworm-rpi5 volumio[1260]: error: The expression evaluated to a falsy value: Nov 23 22:37:27 bookworm-rpi5 volumio[1260]: assert.ok(self.idling) Nov 23 22:37:27 bookworm-rpi5 volumio[1260]: error: updateQueue error: null Nov 23 22:37:27 bookworm-rpi5 systemd[1]: Started mpd.service - Music Player Daemon. Nov 23 22:37:27 bookworm-rpi5 sudo[21863]: pam_unix(sudo:session): session closed for user root Nov 23 22:37:27 bookworm-rpi5 volumio[1260]: error: updateQueue error: null Nov 23 22:37:28 bookworm-rpi5 volumio[1260]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 4 Transport: polling Total Clients: 26 Nov 23 22:37:28 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Nov 23 22:37:28 bookworm-rpi5 volumio[1260]: info: TidalConnect service stoped! Nov 23 22:37:28 bookworm-rpi5 sudo[21940]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 23 22:37:28 bookworm-rpi5 sudo[21940]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 22:37:28 bookworm-rpi5 systemd[1]: Stopping mpd.service - Music Player Daemon... Nov 23 22:37:28 bookworm-rpi5 systemd[1]: mpd.service: Deactivated successfully. Nov 23 22:37:28 bookworm-rpi5 systemd[1]: Stopped mpd.service - Music Player Daemon. Nov 23 22:37:28 bookworm-rpi5 systemd[1]: mpd.socket: Deactivated successfully. Nov 23 22:37:28 bookworm-rpi5 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Nov 23 22:37:28 bookworm-rpi5 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Nov 23 22:37:28 bookworm-rpi5 volumio[1260]: info: TidalConnect service stoped! Nov 23 22:37:28 bookworm-rpi5 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Nov 23 22:37:28 bookworm-rpi5 systemd[1]: Starting mpd.service - Music Player Daemon... Nov 23 22:37:28 bookworm-rpi5 sudo[21948]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 23 22:37:28 bookworm-rpi5 sudo[21948]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Nov 23 22:37:28 bookworm-rpi5 sudo[21948]: pam_unix(sudo:session): session closed for user root Nov 23 22:37:28 bookworm-rpi5 volumio[1260]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Nov 23 22:37:28 bookworm-rpi5 volumio[1260]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Nov 23 22:37:28 bookworm-rpi5 volumio[1260]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Nov 23 22:37:28 bookworm-rpi5 volumio[1260]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Nov 23 22:37:28 bookworm-rpi5 sudo[21963]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Nov 23 22:37:28 bookworm-rpi5 sudo[21963]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 22:37:28 bookworm-rpi5 systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Nov 23 22:37:28 bookworm-rpi5 vtcs[21967]: /usr/bin/vtcs: error while loading shared libraries: libssl.so.1.1: cannot open shared object file: No such file or directory Nov 23 22:37:28 bookworm-rpi5 systemd[1]: vtcs.service: Main process exited, code=exited, status=127/n/a Nov 23 22:37:28 bookworm-rpi5 systemd[1]: vtcs.service: Failed with result 'exit-code'. Nov 23 22:37:28 bookworm-rpi5 sudo[21963]: pam_unix(sudo:session): session closed for user root Nov 23 22:37:29 bookworm-rpi5 sudo[21969]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Nov 23 22:37:29 bookworm-rpi5 sudo[21969]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 22:37:29 bookworm-rpi5 systemd[1]: vtcs.service: Scheduled restart job, restart counter is at 1. Nov 23 22:37:29 bookworm-rpi5 systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Nov 23 22:37:29 bookworm-rpi5 systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Nov 23 22:37:29 bookworm-rpi5 vtcs[21972]: /usr/bin/vtcs: error while loading shared libraries: libssl.so.1.1: cannot open shared object file: No such file or directory Nov 23 22:37:29 bookworm-rpi5 sudo[21969]: pam_unix(sudo:session): session closed for user root Nov 23 22:37:29 bookworm-rpi5 systemd[1]: vtcs.service: Main process exited, code=exited, status=127/n/a Nov 23 22:37:29 bookworm-rpi5 systemd[1]: vtcs.service: Failed with result 'exit-code'. Nov 23 22:37:29 bookworm-rpi5 mpd[21954]: Nov 23 22:37 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 23 22:37:29 bookworm-rpi5 systemd[1]: Started mpd.service - Music Player Daemon. Nov 23 22:37:29 bookworm-rpi5 sudo[21940]: pam_unix(sudo:session): session closed for user root Nov 23 22:37:29 bookworm-rpi5 volumio[1260]: error: updateQueue error: null Nov 23 22:37:29 bookworm-rpi5 systemd[1]: vtcs.service: Scheduled restart job, restart counter is at 2. Nov 23 22:37:29 bookworm-rpi5 systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Nov 23 22:37:29 bookworm-rpi5 systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Nov 23 22:37:29 bookworm-rpi5 vtcs[21977]: /usr/bin/vtcs: error while loading shared libraries: libssl.so.1.1: cannot open shared object file: No such file or directory Nov 23 22:37:29 bookworm-rpi5 systemd[1]: vtcs.service: Main process exited, code=exited, status=127/n/a Nov 23 22:37:29 bookworm-rpi5 systemd[1]: vtcs.service: Failed with result 'exit-code'. Nov 23 22:37:29 bookworm-rpi5 systemd[1]: vtcs.service: Scheduled restart job, restart counter is at 3. Nov 23 22:37:29 bookworm-rpi5 systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Nov 23 22:37:29 bookworm-rpi5 systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Nov 23 22:37:29 bookworm-rpi5 vtcs[21979]: /usr/bin/vtcs: error while loading shared libraries: libssl.so.1.1: cannot open shared object file: No such file or directory Nov 23 22:37:29 bookworm-rpi5 systemd[1]: vtcs.service: Main process exited, code=exited, status=127/n/a Nov 23 22:37:29 bookworm-rpi5 systemd[1]: vtcs.service: Failed with result 'exit-code'. Nov 23 22:37:29 bookworm-rpi5 volumio[1260]: info: VolumeController::SetAlsaVolume100 Nov 23 22:37:29 bookworm-rpi5 volumio[1260]: info: CoreStateMachine::pushState Nov 23 22:37:29 bookworm-rpi5 volumio[1260]: info: CorePlayQueue::getTrack 0 Nov 23 22:37:29 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 22:37:29 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::volumioPushState Nov 23 22:37:29 bookworm-rpi5 volumio[1260]: error: Help! Some callbacks for volumioPushState are crashing! Nov 23 22:37:29 bookworm-rpi5 volumio[1260]: error: Cannot read properties of undefined (reading 'sendVolumeMute') Nov 23 22:37:29 bookworm-rpi5 systemd[1]: vtcs.service: Scheduled restart job, restart counter is at 4. Nov 23 22:37:29 bookworm-rpi5 systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Nov 23 22:37:29 bookworm-rpi5 systemd[1]: vtcs.service: Start request repeated too quickly. Nov 23 22:37:29 bookworm-rpi5 systemd[1]: vtcs.service: Failed with result 'exit-code'. Nov 23 22:37:29 bookworm-rpi5 systemd[1]: Failed to start vtcs.service - Volumio Tidal Connect Service. Nov 23 22:37:30 bookworm-rpi5 volumio[1260]: info: TidalConnect service stoped! Nov 23 22:37:30 bookworm-rpi5 volumio[1260]: info: TidalConnect service stoped! Nov 23 22:37:30 bookworm-rpi5 volumio[1260]: info: TidalConnect service stoped! Nov 23 22:37:30 bookworm-rpi5 volumio[1260]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Nov 23 22:37:30 bookworm-rpi5 volumio[1260]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Nov 23 22:37:30 bookworm-rpi5 volumio[1260]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Nov 23 22:37:30 bookworm-rpi5 volumio[1260]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Nov 23 22:37:30 bookworm-rpi5 volumio[1260]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Nov 23 22:37:30 bookworm-rpi5 volumio[1260]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Nov 23 22:37:30 bookworm-rpi5 sudo[22006]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Nov 23 22:37:30 bookworm-rpi5 sudo[22006]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 22:37:30 bookworm-rpi5 systemd[1]: vtcs.service: Start request repeated too quickly. Nov 23 22:37:30 bookworm-rpi5 systemd[1]: vtcs.service: Failed with result 'exit-code'. Nov 23 22:37:30 bookworm-rpi5 systemd[1]: Failed to start vtcs.service - Volumio Tidal Connect Service. Nov 23 22:37:30 bookworm-rpi5 sudo[22006]: pam_unix(sudo:session): session closed for user root Nov 23 22:37:30 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 22:37:30 bookworm-rpi5 sudo[22009]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Nov 23 22:37:30 bookworm-rpi5 sudo[22009]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 22:37:30 bookworm-rpi5 systemd[1]: vtcs.service: Start request repeated too quickly. Nov 23 22:37:30 bookworm-rpi5 systemd[1]: vtcs.service: Failed with result 'exit-code'. Nov 23 22:37:30 bookworm-rpi5 systemd[1]: Failed to start vtcs.service - Volumio Tidal Connect Service. Nov 23 22:37:30 bookworm-rpi5 sudo[22009]: pam_unix(sudo:session): session closed for user root Nov 23 22:37:30 bookworm-rpi5 volumio[1260]: error: Error starting TidalConnect Command failed: /usr/bin/sudo /bin/systemctl start vtcs.service && sleep 3 Nov 23 22:37:30 bookworm-rpi5 volumio[1260]: Job for vtcs.service failed because the control process exited with error code. Nov 23 22:37:30 bookworm-rpi5 volumio[1260]: See "systemctl status vtcs.service" and "journalctl -xeu vtcs.service" for details. Nov 23 22:37:30 bookworm-rpi5 volumio[1260]: {"cmd":"/usr/bin/sudo /bin/systemctl start vtcs.service && sleep 3","code":1,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/bin/sudo /bin/systemctl start vtcs.service && sleep 3\nJob for vtcs.service failed because the control process exited with error code.\nSee \"systemctl status vtcs.service\" and \"journalctl -xeu vtcs.service\" for details.\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)","stderr":"Job for vtcs.service failed because the control process exited with error code.\nSee \"systemctl status vtcs.service\" and \"journalctl -xeu vtcs.service\" for details.\n","stdout":""} Nov 23 22:37:30 bookworm-rpi5 volumio[1260]: error: Error starting TidalConnect Command failed: /usr/bin/sudo /bin/systemctl start vtcs.service && sleep 3 Nov 23 22:37:30 bookworm-rpi5 volumio[1260]: Job for vtcs.service failed because the control process exited with error code. Nov 23 22:37:30 bookworm-rpi5 volumio[1260]: See "systemctl status vtcs.service" and "journalctl -xeu vtcs.service" for details. Nov 23 22:37:30 bookworm-rpi5 volumio[1260]: {"cmd":"/usr/bin/sudo /bin/systemctl start vtcs.service && sleep 3","code":1,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/bin/sudo /bin/systemctl start vtcs.service && sleep 3\nJob for vtcs.service failed because the control process exited with error code.\nSee \"systemctl status vtcs.service\" and \"journalctl -xeu vtcs.service\" for details.\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)","stderr":"Job for vtcs.service failed because the control process exited with error code.\nSee \"systemctl status vtcs.service\" and \"journalctl -xeu vtcs.service\" for details.\n","stdout":""} Nov 23 22:37:30 bookworm-rpi5 sudo[22012]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Nov 23 22:37:30 bookworm-rpi5 sudo[22012]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 22:37:30 bookworm-rpi5 systemd[1]: vtcs.service: Start request repeated too quickly. Nov 23 22:37:30 bookworm-rpi5 systemd[1]: vtcs.service: Failed with result 'exit-code'. Nov 23 22:37:30 bookworm-rpi5 systemd[1]: Failed to start vtcs.service - Volumio Tidal Connect Service. Nov 23 22:37:30 bookworm-rpi5 sudo[22012]: pam_unix(sudo:session): session closed for user root Nov 23 22:37:30 bookworm-rpi5 volumio[1260]: error: Error starting TidalConnect Command failed: /usr/bin/sudo /bin/systemctl start vtcs.service && sleep 3 Nov 23 22:37:30 bookworm-rpi5 volumio[1260]: Job for vtcs.service failed because the control process exited with error code. Nov 23 22:37:30 bookworm-rpi5 volumio[1260]: See "systemctl status vtcs.service" and "journalctl -xeu vtcs.service" for details. Nov 23 22:37:30 bookworm-rpi5 volumio[1260]: {"cmd":"/usr/bin/sudo /bin/systemctl start vtcs.service && sleep 3","code":1,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/bin/sudo /bin/systemctl start vtcs.service && sleep 3\nJob for vtcs.service failed because the control process exited with error code.\nSee \"systemctl status vtcs.service\" and \"journalctl -xeu vtcs.service\" for details.\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at ChildProcess._handle.onexit (node:internal/child_process:305:5)","stderr":"Job for vtcs.service failed because the control process exited with error code.\nSee \"systemctl status vtcs.service\" and \"journalctl -xeu vtcs.service\" for details.\n","stdout":""} Nov 23 22:37:31 bookworm-rpi5 volumio[1260]: info: TidalConnect service started! Nov 23 22:37:32 bookworm-rpi5 volumio[1260]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Nov 23 22:37:32 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Nov 23 22:37:32 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::volumioGetState Nov 23 22:37:32 bookworm-rpi5 volumio[1260]: info: CorePlayQueue::getTrack 0 Nov 23 22:37:32 bookworm-rpi5 volumio[1260]: info: Restoring Previous Volume level: 100 false true Nov 23 22:37:32 bookworm-rpi5 volumio[1260]: info: VolumeController::SetAlsaVolume100 Nov 23 22:37:32 bookworm-rpi5 volumio[1260]: info: Volume configurations have been set Nov 23 22:37:32 bookworm-rpi5 volumio[1260]: error: Error starting TidalConnect Cannot read properties of undefined (reading 'sendRevoke') {"stack":"TypeError: Cannot read properties of undefined (reading 'sendRevoke')\n at ControllerTidalconnect.stopTidalConnect (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:8524)\n at ControllerTidalconnect.restartService (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:8816)\n at /volumio/node_modules/v-conf/index.js:166:13\n at /volumio/node_modules/multimap/index.js:140:9\n at Array.forEach ()\n at /volumio/node_modules/multimap/index.js:139:13\n at mapEach (/volumio/node_modules/multimap/index.js:9:5)\n at Multimap.forEachEntry (/volumio/node_modules/multimap/index.js:133:5)\n at Multimap.forEach (/volumio/node_modules/multimap/index.js:138:10)\n at Config.set (/volumio/node_modules/v-conf/index.js:163:20)\n at ControllerAlsa.saveVolumeOptions (/volumio/app/plugins/audio_interface/alsa_controller/index.js:983:33)\n at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1097:32)\n at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:337:42)\n at Socket.emit (node:events:514:28)\n at /volumio/node_modules/socket.io/lib/socket.js:503:12\n at process.processTicksAndRejections (node:internal/process/task_queues:77:11)"} Nov 23 22:37:32 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::volumioUpdateVolumeSettings Nov 23 22:37:32 bookworm-rpi5 volumio[1260]: info: Updating Volume Controller Parameters: Device: 3 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Nov 23 22:37:32 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Nov 23 22:37:32 bookworm-rpi5 volumio[1260]: info: Disabling external Volume Control Nov 23 22:37:32 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 23 22:37:32 bookworm-rpi5 volumio[1260]: info: CoreStateMachine::pushState Nov 23 22:37:32 bookworm-rpi5 volumio[1260]: info: CorePlayQueue::getTrack 0 Nov 23 22:37:32 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::volumioPushState Nov 23 22:37:32 bookworm-rpi5 volumio[1260]: error: Help! Some callbacks for volumioPushState are crashing! Nov 23 22:37:32 bookworm-rpi5 volumio[1260]: error: Cannot read properties of undefined (reading 'sendVolumeMute') Nov 23 22:37:32 bookworm-rpi5 volumio[1260]: info: CoreStateMachine::pushState Nov 23 22:37:32 bookworm-rpi5 volumio[1260]: info: CorePlayQueue::getTrack 0 Nov 23 22:37:32 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 22:37:32 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::volumioPushState Nov 23 22:37:32 bookworm-rpi5 sudo[22026]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 23 22:37:32 bookworm-rpi5 volumio[1260]: error: Help! Some callbacks for volumioPushState are crashing! Nov 23 22:37:32 bookworm-rpi5 volumio[1260]: error: Cannot read properties of undefined (reading 'sendVolumeMute') Nov 23 22:37:32 bookworm-rpi5 sudo[22026]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 22:37:32 bookworm-rpi5 volumio[1260]: info: TidalConnect service started! Nov 23 22:37:32 bookworm-rpi5 sudo[22026]: pam_unix(sudo:session): session closed for user root Nov 23 22:37:32 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Nov 23 22:37:32 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 22:37:32 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 23 22:37:32 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 22:37:32 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 22:37:32 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 22:37:32 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 22:37:32 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 22:37:32 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 22:37:32 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 22:37:32 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Nov 23 22:37:32 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 23 22:37:33 bookworm-rpi5 volumio[1260]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 4 Transport: polling Total Clients: 26 Nov 23 22:37:35 bookworm-rpi5 volumio[1260]: info: TidalConnect service stoped! Nov 23 22:37:35 bookworm-rpi5 volumio[1260]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Nov 23 22:37:35 bookworm-rpi5 volumio[1260]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Nov 23 22:37:35 bookworm-rpi5 sudo[22058]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Nov 23 22:37:35 bookworm-rpi5 sudo[22058]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 23 22:37:35 bookworm-rpi5 systemd[1]: vtcs.service: Start request repeated too quickly. Nov 23 22:37:35 bookworm-rpi5 systemd[1]: vtcs.service: Failed with result 'exit-code'. Nov 23 22:37:35 bookworm-rpi5 systemd[1]: Failed to start vtcs.service - Volumio Tidal Connect Service. Nov 23 22:37:35 bookworm-rpi5 sudo[22058]: pam_unix(sudo:session): session closed for user root Nov 23 22:37:35 bookworm-rpi5 volumio[1260]: error: Error starting TidalConnect Command failed: /usr/bin/sudo /bin/systemctl start vtcs.service && sleep 3 Nov 23 22:37:35 bookworm-rpi5 volumio[1260]: Job for vtcs.service failed because the control process exited with error code. Nov 23 22:37:35 bookworm-rpi5 volumio[1260]: See "systemctl status vtcs.service" and "journalctl -xeu vtcs.service" for details. Nov 23 22:37:35 bookworm-rpi5 volumio[1260]: {"cmd":"/usr/bin/sudo /bin/systemctl start vtcs.service && sleep 3","code":1,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/bin/sudo /bin/systemctl start vtcs.service && sleep 3\nJob for vtcs.service failed because the control process exited with error code.\nSee \"systemctl status vtcs.service\" and \"journalctl -xeu vtcs.service\" for details.\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at ChildProcess._handle.onexit (node:internal/child_process:305:5)","stderr":"Job for vtcs.service failed because the control process exited with error code.\nSee \"systemctl status vtcs.service\" and \"journalctl -xeu vtcs.service\" for details.\n","stdout":""} Nov 23 22:37:36 bookworm-rpi5 volumio[1260]: info: VolumeController::SetAlsaVolume100 Nov 23 22:37:36 bookworm-rpi5 volumio[1260]: info: CoreStateMachine::pushState Nov 23 22:37:36 bookworm-rpi5 volumio[1260]: info: CorePlayQueue::getTrack 0 Nov 23 22:37:36 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 22:37:36 bookworm-rpi5 volumio[1260]: info: CoreCommandRouter::volumioPushState Nov 23 22:37:36 bookworm-rpi5 volumio[1260]: error: Help! Some callbacks for volumioPushState are crashing! Nov 23 22:37:36 bookworm-rpi5 volumio[1260]: error: Cannot read properties of undefined (reading 'sendVolumeMute') Nov 23 22:37:38 bookworm-rpi5 volumio[1260]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 4 Transport: polling Total Clients: 26 Nov 23 22:37:40 bookworm-rpi5 volumio[1260]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 23 22:37:40 bookworm-rpi5 volumio[1260]: TypeError: Cannot read properties of undefined (reading 'address') Nov 23 22:37:40 bookworm-rpi5 volumio[1260]: at Bluetoothout.pairBtDevice (/data/plugins/audio_interface/Bluetoothout/index.js:546:61) Nov 23 22:37:40 bookworm-rpi5 volumio[1260]: at Timeout._onTimeout (/data/plugins/audio_interface/Bluetoothout/index.js:47:15) Nov 23 22:37:40 bookworm-rpi5 volumio[1260]: at listOnTimeout (node:internal/timers:573:17) Nov 23 22:37:40 bookworm-rpi5 volumio[1260]: at process.processTimers (node:internal/timers:514:7) Nov 23 22:37:40 bookworm-rpi5 volumio[1260]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 23 22:37:40 bookworm-rpi5 sudo[22075]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2024-11-23 22:36' Nov 23 22:37:40 bookworm-rpi5 sudo[22075]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="63c682653ce156d0a511068b635ad11a88ca34c3" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Nov 22 12:42:05 UTC 2024" VOLUMIO_VERSION="0.018" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="7ebd7e4d5f8447adc1b1160a00713215"