-- Logs begin at Tue 2023-05-09 09:48:21 CST, end at Mon 2024-08-19 23:40:44 CST. -- Aug 19 23:39:00 volumio systemd[1]: volspotconnect.service: Service RestartSec=2s expired, scheduling restart. Aug 19 23:39:00 volumio systemd[1]: volspotconnect.service: Scheduled restart job, restart counter is at 33. Aug 19 23:39:00 volumio systemd[1]: Stopped Volspotconnect2 Daemon. Aug 19 23:39:00 volumio systemd[1]: Started Volspotconnect2 Daemon. Aug 19 23:39:00 volumio volumio[2599]: /usr/lib/startconnect.sh: line 2: ./usr/bin/vollibrespot: No such file or directory Aug 19 23:39:00 volumio systemd[1]: volspotconnect.service: Main process exited, code=exited, status=127/n/a Aug 19 23:39:00 volumio systemd[1]: volspotconnect.service: Failed with result 'exit-code'. Aug 19 23:39:02 volumio systemd[1]: volspotconnect.service: Service RestartSec=2s expired, scheduling restart. Aug 19 23:39:02 volumio systemd[1]: volspotconnect.service: Scheduled restart job, restart counter is at 34. Aug 19 23:39:02 volumio systemd[1]: Stopped Volspotconnect2 Daemon. Aug 19 23:39:02 volumio systemd[1]: Started Volspotconnect2 Daemon. Aug 19 23:39:02 volumio volumio[2601]: /usr/lib/startconnect.sh: line 2: ./usr/bin/vollibrespot: No such file or directory Aug 19 23:39:02 volumio systemd[1]: volspotconnect.service: Main process exited, code=exited, status=127/n/a Aug 19 23:39:02 volumio systemd[1]: volspotconnect.service: Failed with result 'exit-code'. Aug 19 23:39:02 volumio volumio[589]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 19 23:39:02 volumio volumio[589]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Aug 19 23:39:02 volumio volumio[589]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Aug 19 23:39:02 volumio volumio[589]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Aug 19 23:39:03 volumio volumio[589]: info: Received Get System Version Aug 19 23:39:03 volumio volumio[589]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 19 23:39:04 volumio systemd[1]: volspotconnect.service: Service RestartSec=2s expired, scheduling restart. Aug 19 23:39:04 volumio systemd[1]: volspotconnect.service: Scheduled restart job, restart counter is at 35. Aug 19 23:39:04 volumio systemd[1]: Stopped Volspotconnect2 Daemon. Aug 19 23:39:04 volumio systemd[1]: Started Volspotconnect2 Daemon. Aug 19 23:39:04 volumio volumio[2604]: /usr/lib/startconnect.sh: line 2: ./usr/bin/vollibrespot: No such file or directory Aug 19 23:39:04 volumio systemd[1]: volspotconnect.service: Main process exited, code=exited, status=127/n/a Aug 19 23:39:04 volumio systemd[1]: volspotconnect.service: Failed with result 'exit-code'. Aug 19 23:39:06 volumio systemd[1]: volspotconnect.service: Service RestartSec=2s expired, scheduling restart. Aug 19 23:39:06 volumio systemd[1]: volspotconnect.service: Scheduled restart job, restart counter is at 36. Aug 19 23:39:06 volumio systemd[1]: Stopped Volspotconnect2 Daemon. Aug 19 23:39:06 volumio systemd[1]: Started Volspotconnect2 Daemon. Aug 19 23:39:07 volumio volumio[2606]: /usr/lib/startconnect.sh: line 2: ./usr/bin/vollibrespot: No such file or directory Aug 19 23:39:07 volumio systemd[1]: volspotconnect.service: Main process exited, code=exited, status=127/n/a Aug 19 23:39:07 volumio systemd[1]: volspotconnect.service: Failed with result 'exit-code'. Aug 19 23:39:08 volumio volumio[589]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 19 23:39:08 volumio volumio[589]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 19 23:39:09 volumio systemd[1]: volspotconnect.service: Service RestartSec=2s expired, scheduling restart. Aug 19 23:39:09 volumio systemd[1]: volspotconnect.service: Scheduled restart job, restart counter is at 37. Aug 19 23:39:09 volumio systemd[1]: Stopped Volspotconnect2 Daemon. Aug 19 23:39:09 volumio systemd[1]: Started Volspotconnect2 Daemon. Aug 19 23:39:09 volumio volumio[2608]: /usr/lib/startconnect.sh: line 2: ./usr/bin/vollibrespot: No such file or directory Aug 19 23:39:09 volumio systemd[1]: volspotconnect.service: Main process exited, code=exited, status=127/n/a Aug 19 23:39:09 volumio systemd[1]: volspotconnect.service: Failed with result 'exit-code'. Aug 19 23:39:11 volumio systemd[1]: volspotconnect.service: Service RestartSec=2s expired, scheduling restart. Aug 19 23:39:11 volumio systemd[1]: volspotconnect.service: Scheduled restart job, restart counter is at 38. Aug 19 23:39:11 volumio systemd[1]: Stopped Volspotconnect2 Daemon. Aug 19 23:39:11 volumio systemd[1]: Started Volspotconnect2 Daemon. Aug 19 23:39:11 volumio volumio[2610]: /usr/lib/startconnect.sh: line 2: ./usr/bin/vollibrespot: No such file or directory Aug 19 23:39:11 volumio systemd[1]: volspotconnect.service: Main process exited, code=exited, status=127/n/a Aug 19 23:39:11 volumio systemd[1]: volspotconnect.service: Failed with result 'exit-code'. Aug 19 23:39:12 volumio volumio[589]: info: Starting Uninstall of plugin music_service - spop Aug 19 23:39:12 volumio volumio[589]: info: Uninstalling plugin spop Aug 19 23:39:12 volumio volumio[589]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify Aug 19 23:39:12 volumio volumio[589]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 19 23:39:12 volumio volumio[589]: Cannot find translation for sourceMedia Servers Aug 19 23:39:12 volumio volumio[589]: [SpotifyConnect] Closing metadata listener Aug 19 23:39:12 volumio volumio[589]: info: Disabling plugin spop Aug 19 23:39:12 volumio volumio[589]: info: Checking if uninstall.sh is present Aug 19 23:39:12 volumio volumio[589]: info: Executing uninstall.sh Aug 19 23:39:12 volumio sudo[2613]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volspotconnect.service Aug 19 23:39:12 volumio sudo[2613]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 23:39:12 volumio systemd[1]: Stopped Volspotconnect2 Daemon. Aug 19 23:39:12 volumio sudo[2613]: pam_unix(sudo:session): session closed for user root Aug 19 23:39:13 volumio volumio[589]: [SpotifyConnect] Vollibrespot Daemon service stoped! Aug 19 23:39:13 volumio sudo[2617]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/spop/uninstall.sh Aug 19 23:39:13 volumio sudo[2617]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 23:39:13 volumio sudo[2617]: pam_unix(sudo:session): session closed for user root Aug 19 23:39:13 volumio volumio[589]: info: Uninstall script completed Aug 19 23:39:13 volumio volumio[589]: info: Removing plugin spop from configuration Aug 19 23:39:13 volumio volumio[589]: info: Successfully removed spop configuration files Aug 19 23:39:13 volumio volumio[589]: info: Plugin folders cleanup Aug 19 23:39:13 volumio volumio[589]: info: Scanning into folder /volumio/app/plugins/ Aug 19 23:39:13 volumio volumio[589]: info: Scanning category audio_interface Aug 19 23:39:13 volumio volumio[589]: info: Scanning category miscellanea Aug 19 23:39:13 volumio volumio[589]: info: Scanning category music_service Aug 19 23:39:13 volumio volumio[589]: info: Scanning category plugins.json Aug 19 23:39:13 volumio volumio[589]: info: Scanning category system_controller Aug 19 23:39:13 volumio volumio[589]: info: Scanning category user_interface Aug 19 23:39:13 volumio volumio[589]: info: Scanning into folder /data/plugins/ Aug 19 23:39:13 volumio volumio[589]: info: Scanning category music_service Aug 19 23:39:13 volumio volumio[589]: info: Cleaning folder for spop Aug 19 23:39:18 volumio volumio[589]: info: Plugin folders cleanup completed Aug 19 23:39:18 volumio volumio[589]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 19 23:39:18 volumio volumio[589]: info: [] Aug 19 23:39:18 volumio volumio[589]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 19 23:39:33 volumio volumio[589]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 19 23:39:36 volumio volumio[589]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/download/spop/4.0.2/volumio/buster/armhf Aug 19 23:39:40 volumio volumio[589]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/download/spop/4.0.2/volumio/buster/armhf Aug 19 23:39:40 volumio volumio[589]: info: Folder /tmp/plugins removed Aug 19 23:39:45 volumio volumio[589]: info: Check plugin dependencies Aug 19 23:39:45 volumio volumio[589]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 19 23:39:45 volumio volumio[589]: info: Checking if plugin already exists Aug 19 23:39:45 volumio volumio[589]: info: Rename folder Aug 19 23:39:45 volumio volumio[589]: info: Folder /tmp/downloaded_plugin.zip removed Aug 19 23:39:46 volumio volumio[589]: info: Move to category Aug 19 23:39:48 volumio volumio[589]: info: Checking if install.sh is present Aug 19 23:39:48 volumio volumio[589]: info: Executing install.sh Aug 19 23:39:49 volumio sudo[2640]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/spop/install.sh Aug 19 23:39:49 volumio sudo[2640]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 23:39:49 volumio volumio[589]: info: Installing Go-librespot Aug 19 23:39:49 volumio volumio[589]: info: Checking old vollibrespot installs Aug 19 23:39:49 volumio systemd[1]: Reloading. Aug 19 23:39:52 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Aug 19 23:39:55 volumio systemd[1]: Reloading. Aug 19 23:39:58 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Aug 19 23:40:00 volumio volumio[589]: info: Dowloading daemon Aug 19 23:40:07 volumio volumio[589]: info: Creating Start Script Aug 19 23:40:07 volumio systemd[1]: Reloading. Aug 19 23:40:10 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Aug 19 23:40:12 volumio sudo[2640]: pam_unix(sudo:session): session closed for user root Aug 19 23:40:12 volumio volumio[589]: Plugin install end detected on script Aug 19 23:40:12 volumio volumio[589]: info: Install script completed Aug 19 23:40:12 volumio volumio[589]: info: Adding reference to registry Aug 19 23:40:12 volumio volumio[589]: info: Done installing plugin. Aug 19 23:40:12 volumio volumio[589]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 19 23:40:12 volumio volumio[589]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 19 23:40:12 volumio volumio[589]: info: Folder /tmp/plugins removed Aug 19 23:40:12 volumio volumio[589]: info: Folder /tmp/downloaded_plugin.zip removed Aug 19 23:40:12 volumio volumio[589]: info: Folder /data/temp removed Aug 19 23:40:26 volumio volumio[589]: info: Enabling plugin spop Aug 19 23:40:26 volumio volumio[589]: info: Loading plugin "spop"... Aug 19 23:40:26 volumio volumio[589]: info: PLUGIN START: spop Aug 19 23:40:26 volumio volumio[589]: info: Checking for old spotify connect plugin installed Aug 19 23:40:26 volumio volumio[589]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 19 23:40:26 volumio volumio[589]: [SpotifyConnect] Creating VLS config file Aug 19 23:40:26 volumio volumio[589]: error: Failed to read template file: Error: ENOENT: no such file or directory, open '/data/plugins/music_service/spop/volspotify.tmpl' Aug 19 23:40:26 volumio volumio[589]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 19 23:40:26 volumio volumio[589]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 19 23:40:26 volumio volumio[589]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 19 23:40:26 volumio volumio[589]: [SpotifyConnect] Starting metadata listener Aug 19 23:40:26 volumio volumio[589]: [SpotifyConnect] Error: bind EADDRINUSE 0.0.0.0:5030 Aug 19 23:40:26 volumio volumio[589]: at dgram.js:337:20 Aug 19 23:40:26 volumio volumio[589]: at processTicksAndRejections (internal/process/task_queues.js:81:21) Aug 19 23:40:26 volumio volumio[589]: (node:589) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'replace' of undefined Aug 19 23:40:26 volumio volumio[589]: at ControllerSpotify.createConfigFile (/data/plugins/music_service/spop/index.js:2550:27) Aug 19 23:40:26 volumio volumio[589]: at ControllerSpotify.init (/data/plugins/music_service/spop/index.js:2413:14) Aug 19 23:40:26 volumio volumio[589]: at Promise._successFn (/data/plugins/music_service/spop/index.js:133:14) Aug 19 23:40:26 volumio volumio[589]: at nextTickCallback (/data/plugins/music_service/spop/node_modules/kew/kew.js:47:28) Aug 19 23:40:26 volumio volumio[589]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Aug 19 23:40:26 volumio volumio[589]: (node:589) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 8) Aug 19 23:40:26 volumio sudo[2700]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service Aug 19 23:40:26 volumio sudo[2700]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 23:40:26 volumio volumio[589]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 6 Aug 19 23:40:26 volumio systemd[1]: Started Volspotconnect2 Daemon. Aug 19 23:40:26 volumio sudo[2700]: pam_unix(sudo:session): session closed for user root Aug 19 23:40:26 volumio volumio[2702]: /usr/lib/startconnect.sh: line 2: ./usr/bin/vollibrespot: No such file or directory Aug 19 23:40:26 volumio systemd[1]: volspotconnect.service: Main process exited, code=exited, status=127/n/a Aug 19 23:40:26 volumio systemd[1]: volspotconnect.service: Failed with result 'exit-code'. Aug 19 23:40:26 volumio volumio[589]: [SpotifyConnect] Vollibrespot Daemon service started! Aug 19 23:40:26 volumio volumio[589]: [Metrics] SpotifyConnect: 0s 784.38ms Aug 19 23:40:26 volumio volumio[589]: info: Done. Aug 19 23:40:29 volumio systemd[1]: volspotconnect.service: Service RestartSec=2s expired, scheduling restart. Aug 19 23:40:29 volumio systemd[1]: volspotconnect.service: Scheduled restart job, restart counter is at 1. Aug 19 23:40:29 volumio systemd[1]: Stopped Volspotconnect2 Daemon. Aug 19 23:40:29 volumio systemd[1]: Started Volspotconnect2 Daemon. Aug 19 23:40:29 volumio volumio[2704]: /usr/lib/startconnect.sh: line 2: ./usr/bin/vollibrespot: No such file or directory Aug 19 23:40:29 volumio systemd[1]: volspotconnect.service: Main process exited, code=exited, status=127/n/a Aug 19 23:40:29 volumio systemd[1]: volspotconnect.service: Failed with result 'exit-code'. Aug 19 23:40:30 volumio volumio[589]: info: Checking Spotify Web API Aug 19 23:40:31 volumio systemd[1]: volspotconnect.service: Service RestartSec=2s expired, scheduling restart. Aug 19 23:40:31 volumio systemd[1]: volspotconnect.service: Scheduled restart job, restart counter is at 2. Aug 19 23:40:31 volumio systemd[1]: Stopped Volspotconnect2 Daemon. Aug 19 23:40:31 volumio systemd[1]: Started Volspotconnect2 Daemon. Aug 19 23:40:31 volumio volumio[2706]: /usr/lib/startconnect.sh: line 2: ./usr/bin/vollibrespot: No such file or directory Aug 19 23:40:31 volumio systemd[1]: volspotconnect.service: Main process exited, code=exited, status=127/n/a Aug 19 23:40:31 volumio systemd[1]: volspotconnect.service: Failed with result 'exit-code'. Aug 19 23:40:32 volumio volumio[589]: info: Enabling plugin spop Aug 19 23:40:32 volumio volumio[589]: info: Loading plugin "spop"... Aug 19 23:40:32 volumio volumio[589]: info: PLUGIN START: spop Aug 19 23:40:32 volumio volumio[589]: info: Checking for old spotify connect plugin installed Aug 19 23:40:32 volumio volumio[589]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 19 23:40:32 volumio volumio[589]: [SpotifyConnect] Creating VLS config file Aug 19 23:40:32 volumio volumio[589]: error: Failed to read template file: Error: ENOENT: no such file or directory, open '/data/plugins/music_service/spop/volspotify.tmpl' Aug 19 23:40:32 volumio volumio[589]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 19 23:40:32 volumio volumio[589]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 19 23:40:32 volumio volumio[589]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 19 23:40:32 volumio volumio[589]: [SpotifyConnect] Starting metadata listener Aug 19 23:40:32 volumio volumio[589]: [SpotifyConnect] Error: bind EADDRINUSE 0.0.0.0:5030 Aug 19 23:40:32 volumio volumio[589]: at dgram.js:337:20 Aug 19 23:40:32 volumio volumio[589]: at processTicksAndRejections (internal/process/task_queues.js:81:21) Aug 19 23:40:32 volumio volumio[589]: (node:589) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'replace' of undefined Aug 19 23:40:32 volumio volumio[589]: at ControllerSpotify.createConfigFile (/data/plugins/music_service/spop/index.js:2550:27) Aug 19 23:40:32 volumio volumio[589]: at ControllerSpotify.init (/data/plugins/music_service/spop/index.js:2413:14) Aug 19 23:40:32 volumio volumio[589]: at Promise._successFn (/data/plugins/music_service/spop/index.js:133:14) Aug 19 23:40:32 volumio volumio[589]: at nextTickCallback (/data/plugins/music_service/spop/node_modules/kew/kew.js:47:28) Aug 19 23:40:32 volumio volumio[589]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Aug 19 23:40:32 volumio volumio[589]: (node:589) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 9) Aug 19 23:40:33 volumio volumio[589]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 6 Aug 19 23:40:33 volumio sudo[2709]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service Aug 19 23:40:33 volumio sudo[2709]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 23:40:33 volumio volumio[589]: info: Enabling plugin spop Aug 19 23:40:33 volumio volumio[589]: info: Loading plugin "spop"... Aug 19 23:40:33 volumio volumio[589]: info: PLUGIN START: spop Aug 19 23:40:33 volumio volumio[589]: info: Checking for old spotify connect plugin installed Aug 19 23:40:33 volumio volumio[589]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 19 23:40:33 volumio volumio[589]: [SpotifyConnect] Creating VLS config file Aug 19 23:40:33 volumio volumio[589]: error: Failed to read template file: Error: ENOENT: no such file or directory, open '/data/plugins/music_service/spop/volspotify.tmpl' Aug 19 23:40:33 volumio volumio[589]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 19 23:40:33 volumio volumio[589]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 19 23:40:33 volumio volumio[589]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 19 23:40:33 volumio volumio[589]: [SpotifyConnect] Starting metadata listener Aug 19 23:40:33 volumio volumio[589]: [SpotifyConnect] Error: bind EADDRINUSE 0.0.0.0:5030 Aug 19 23:40:33 volumio volumio[589]: at dgram.js:337:20 Aug 19 23:40:33 volumio volumio[589]: at processTicksAndRejections (internal/process/task_queues.js:81:21) Aug 19 23:40:33 volumio volumio[589]: (node:589) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'replace' of undefined Aug 19 23:40:33 volumio volumio[589]: at ControllerSpotify.createConfigFile (/data/plugins/music_service/spop/index.js:2550:27) Aug 19 23:40:33 volumio volumio[589]: at ControllerSpotify.init (/data/plugins/music_service/spop/index.js:2413:14) Aug 19 23:40:33 volumio volumio[589]: at Promise._successFn (/data/plugins/music_service/spop/index.js:133:14) Aug 19 23:40:33 volumio volumio[589]: at nextTickCallback (/data/plugins/music_service/spop/node_modules/kew/kew.js:47:28) Aug 19 23:40:33 volumio volumio[589]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Aug 19 23:40:33 volumio volumio[589]: (node:589) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 10) Aug 19 23:40:33 volumio systemd[1]: volspotconnect.service: Service RestartSec=2s expired, scheduling restart. Aug 19 23:40:33 volumio systemd[1]: volspotconnect.service: Scheduled restart job, restart counter is at 3. Aug 19 23:40:33 volumio systemd[1]: Stopped Volspotconnect2 Daemon. Aug 19 23:40:33 volumio volumio[589]: info: Enabling plugin spop Aug 19 23:40:33 volumio systemd[1]: Started Volspotconnect2 Daemon. Aug 19 23:40:33 volumio sudo[2709]: pam_unix(sudo:session): session closed for user root Aug 19 23:40:33 volumio volumio[589]: info: Loading plugin "spop"... Aug 19 23:40:33 volumio volumio[2713]: /usr/lib/startconnect.sh: line 2: ./usr/bin/vollibrespot: No such file or directory Aug 19 23:40:33 volumio systemd[1]: volspotconnect.service: Main process exited, code=exited, status=127/n/a Aug 19 23:40:33 volumio sudo[2712]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service Aug 19 23:40:33 volumio systemd[1]: volspotconnect.service: Failed with result 'exit-code'. Aug 19 23:40:33 volumio sudo[2712]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 23:40:33 volumio volumio[589]: info: PLUGIN START: spop Aug 19 23:40:33 volumio volumio[589]: info: Checking for old spotify connect plugin installed Aug 19 23:40:33 volumio volumio[589]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 19 23:40:33 volumio volumio[589]: [SpotifyConnect] Creating VLS config file Aug 19 23:40:33 volumio volumio[589]: error: Failed to read template file: Error: ENOENT: no such file or directory, open '/data/plugins/music_service/spop/volspotify.tmpl' Aug 19 23:40:33 volumio volumio[589]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 19 23:40:33 volumio volumio[589]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 19 23:40:33 volumio volumio[589]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 19 23:40:33 volumio volumio[589]: [SpotifyConnect] Starting metadata listener Aug 19 23:40:34 volumio volumio[589]: [SpotifyConnect] Error: bind EADDRINUSE 0.0.0.0:5030 Aug 19 23:40:34 volumio volumio[589]: at dgram.js:337:20 Aug 19 23:40:34 volumio volumio[589]: at processTicksAndRejections (internal/process/task_queues.js:81:21) Aug 19 23:40:34 volumio volumio[589]: (node:589) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'replace' of undefined Aug 19 23:40:34 volumio volumio[589]: at ControllerSpotify.createConfigFile (/data/plugins/music_service/spop/index.js:2550:27) Aug 19 23:40:34 volumio volumio[589]: at ControllerSpotify.init (/data/plugins/music_service/spop/index.js:2413:14) Aug 19 23:40:34 volumio volumio[589]: at Promise._successFn (/data/plugins/music_service/spop/index.js:133:14) Aug 19 23:40:34 volumio volumio[589]: at nextTickCallback (/data/plugins/music_service/spop/node_modules/kew/kew.js:47:28) Aug 19 23:40:34 volumio volumio[589]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Aug 19 23:40:34 volumio volumio[589]: (node:589) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 11) Aug 19 23:40:34 volumio volumio[589]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 6 Aug 19 23:40:34 volumio sudo[2717]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service Aug 19 23:40:34 volumio sudo[2717]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 23:40:34 volumio volumio[589]: [SpotifyConnect] Vollibrespot Daemon service started! Aug 19 23:40:34 volumio volumio[589]: [Metrics] SpotifyConnect: 0s 494.96ms Aug 19 23:40:34 volumio volumio[589]: info: Done. Aug 19 23:40:34 volumio volumio[589]: info: Enabling plugin spop Aug 19 23:40:34 volumio volumio[589]: info: Loading plugin "spop"... Aug 19 23:40:34 volumio volumio[589]: info: PLUGIN START: spop Aug 19 23:40:34 volumio volumio[589]: info: Checking for old spotify connect plugin installed Aug 19 23:40:34 volumio volumio[589]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 19 23:40:34 volumio volumio[589]: [SpotifyConnect] Creating VLS config file Aug 19 23:40:34 volumio volumio[589]: error: Failed to read template file: Error: ENOENT: no such file or directory, open '/data/plugins/music_service/spop/volspotify.tmpl' Aug 19 23:40:34 volumio volumio[589]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 19 23:40:34 volumio volumio[589]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 19 23:40:34 volumio volumio[589]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 19 23:40:34 volumio volumio[589]: [SpotifyConnect] Starting metadata listener Aug 19 23:40:34 volumio volumio[589]: [SpotifyConnect] Error: bind EADDRINUSE 0.0.0.0:5030 Aug 19 23:40:34 volumio volumio[589]: at dgram.js:337:20 Aug 19 23:40:34 volumio volumio[589]: at processTicksAndRejections (internal/process/task_queues.js:81:21) Aug 19 23:40:34 volumio volumio[589]: (node:589) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'replace' of undefined Aug 19 23:40:34 volumio volumio[589]: at ControllerSpotify.createConfigFile (/data/plugins/music_service/spop/index.js:2550:27) Aug 19 23:40:34 volumio volumio[589]: at ControllerSpotify.init (/data/plugins/music_service/spop/index.js:2413:14) Aug 19 23:40:34 volumio volumio[589]: at Promise._successFn (/data/plugins/music_service/spop/index.js:133:14) Aug 19 23:40:34 volumio volumio[589]: at nextTickCallback (/data/plugins/music_service/spop/node_modules/kew/kew.js:47:28) Aug 19 23:40:34 volumio volumio[589]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Aug 19 23:40:34 volumio volumio[589]: (node:589) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 12) Aug 19 23:40:34 volumio volumio[589]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 7 Aug 19 23:40:34 volumio volumio[589]: info: Enabling plugin spop Aug 19 23:40:34 volumio volumio[589]: info: Loading plugin "spop"... Aug 19 23:40:34 volumio sudo[2720]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service Aug 19 23:40:34 volumio sudo[2720]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 23:40:34 volumio volumio[589]: info: PLUGIN START: spop Aug 19 23:40:34 volumio volumio[589]: info: Checking for old spotify connect plugin installed Aug 19 23:40:35 volumio volumio[589]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 19 23:40:35 volumio volumio[589]: [SpotifyConnect] Creating VLS config file Aug 19 23:40:35 volumio volumio[589]: error: Failed to read template file: Error: ENOENT: no such file or directory, open '/data/plugins/music_service/spop/volspotify.tmpl' Aug 19 23:40:35 volumio volumio[589]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 19 23:40:35 volumio volumio[589]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 19 23:40:35 volumio volumio[589]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 19 23:40:35 volumio volumio[589]: [SpotifyConnect] Starting metadata listener Aug 19 23:40:35 volumio volumio[589]: [SpotifyConnect] Error: bind EADDRINUSE 0.0.0.0:5030 Aug 19 23:40:35 volumio volumio[589]: at dgram.js:337:20 Aug 19 23:40:35 volumio volumio[589]: at processTicksAndRejections (internal/process/task_queues.js:81:21) Aug 19 23:40:35 volumio volumio[589]: (node:589) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'replace' of undefined Aug 19 23:40:35 volumio volumio[589]: at ControllerSpotify.createConfigFile (/data/plugins/music_service/spop/index.js:2550:27) Aug 19 23:40:35 volumio volumio[589]: at ControllerSpotify.init (/data/plugins/music_service/spop/index.js:2413:14) Aug 19 23:40:35 volumio volumio[589]: at Promise._successFn (/data/plugins/music_service/spop/index.js:133:14) Aug 19 23:40:35 volumio volumio[589]: at nextTickCallback (/data/plugins/music_service/spop/node_modules/kew/kew.js:47:28) Aug 19 23:40:35 volumio volumio[589]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Aug 19 23:40:35 volumio volumio[589]: (node:589) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 13) Aug 19 23:40:35 volumio volumio[589]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 8 Aug 19 23:40:35 volumio sudo[2723]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service Aug 19 23:40:35 volumio sudo[2723]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 23:40:35 volumio volumio[589]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 9 Aug 19 23:40:36 volumio systemd[1]: volspotconnect.service: Service RestartSec=2s expired, scheduling restart. Aug 19 23:40:36 volumio systemd[1]: volspotconnect.service: Scheduled restart job, restart counter is at 4. Aug 19 23:40:36 volumio systemd[1]: Stopped Volspotconnect2 Daemon. Aug 19 23:40:36 volumio systemd[1]: Started Volspotconnect2 Daemon. Aug 19 23:40:36 volumio sudo[2717]: pam_unix(sudo:session): session closed for user root Aug 19 23:40:36 volumio sudo[2720]: pam_unix(sudo:session): session closed for user root Aug 19 23:40:36 volumio sudo[2712]: pam_unix(sudo:session): session closed for user root Aug 19 23:40:36 volumio sudo[2723]: pam_unix(sudo:session): session closed for user root Aug 19 23:40:36 volumio volumio[2725]: /usr/lib/startconnect.sh: line 2: ./usr/bin/vollibrespot: No such file or directory Aug 19 23:40:36 volumio systemd[1]: volspotconnect.service: Main process exited, code=exited, status=127/n/a Aug 19 23:40:36 volumio systemd[1]: volspotconnect.service: Failed with result 'exit-code'. Aug 19 23:40:36 volumio volumio[589]: [SpotifyConnect] Vollibrespot Daemon service started! Aug 19 23:40:36 volumio volumio[589]: [Metrics] SpotifyConnect: 1s 347.79ms Aug 19 23:40:36 volumio volumio[589]: info: Done. Aug 19 23:40:36 volumio volumio[589]: [SpotifyConnect] Vollibrespot Daemon service started! Aug 19 23:40:36 volumio volumio[589]: [Metrics] SpotifyConnect: 1s 443.78ms Aug 19 23:40:36 volumio volumio[589]: info: Done. Aug 19 23:40:36 volumio volumio[589]: [SpotifyConnect] Vollibrespot Daemon service started! Aug 19 23:40:36 volumio volumio[589]: [Metrics] SpotifyConnect: 1s 474.80ms Aug 19 23:40:36 volumio volumio[589]: info: Done. Aug 19 23:40:36 volumio volumio[589]: [SpotifyConnect] Vollibrespot Daemon service started! Aug 19 23:40:36 volumio volumio[589]: [Metrics] SpotifyConnect: 1s 509.81ms Aug 19 23:40:36 volumio volumio[589]: info: Done. Aug 19 23:40:37 volumio volumio[589]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify Aug 19 23:40:37 volumio volumio[589]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 19 23:40:37 volumio volumio[589]: Cannot find translation for sourceMedia Servers Aug 19 23:40:37 volumio volumio[589]: [SpotifyConnect] Closing metadata listener Aug 19 23:40:37 volumio volumio[589]: info: Disabling plugin spop Aug 19 23:40:37 volumio volumio[589]: info: Done. Aug 19 23:40:37 volumio sudo[2728]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volspotconnect.service Aug 19 23:40:37 volumio sudo[2728]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 23:40:38 volumio systemd[1]: Stopped Volspotconnect2 Daemon. Aug 19 23:40:38 volumio sudo[2728]: pam_unix(sudo:session): session closed for user root Aug 19 23:40:38 volumio volumio[589]: [SpotifyConnect] Vollibrespot Daemon service stoped! Aug 19 23:40:38 volumio volumio[589]: info: Checking Spotify Web API Aug 19 23:40:40 volumio volumio[589]: info: Enabling plugin spop Aug 19 23:40:40 volumio volumio[589]: info: Loading plugin "spop"... Aug 19 23:40:40 volumio volumio[589]: info: PLUGIN START: spop Aug 19 23:40:40 volumio volumio[589]: info: Checking for old spotify connect plugin installed Aug 19 23:40:40 volumio volumio[589]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 19 23:40:40 volumio volumio[589]: [SpotifyConnect] Creating VLS config file Aug 19 23:40:40 volumio volumio[589]: error: Failed to read template file: Error: ENOENT: no such file or directory, open '/data/plugins/music_service/spop/volspotify.tmpl' Aug 19 23:40:40 volumio volumio[589]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 19 23:40:40 volumio volumio[589]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 19 23:40:40 volumio volumio[589]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 19 23:40:40 volumio volumio[589]: [SpotifyConnect] Starting metadata listener Aug 19 23:40:40 volumio volumio[589]: [SpotifyConnect] Error: bind EADDRINUSE 0.0.0.0:5030 Aug 19 23:40:40 volumio volumio[589]: at dgram.js:337:20 Aug 19 23:40:40 volumio volumio[589]: at processTicksAndRejections (internal/process/task_queues.js:81:21) Aug 19 23:40:40 volumio volumio[589]: (node:589) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'replace' of undefined Aug 19 23:40:40 volumio volumio[589]: at ControllerSpotify.createConfigFile (/data/plugins/music_service/spop/index.js:2550:27) Aug 19 23:40:40 volumio volumio[589]: at ControllerSpotify.init (/data/plugins/music_service/spop/index.js:2413:14) Aug 19 23:40:40 volumio volumio[589]: at Promise._successFn (/data/plugins/music_service/spop/index.js:133:14) Aug 19 23:40:40 volumio volumio[589]: at nextTickCallback (/data/plugins/music_service/spop/node_modules/kew/kew.js:47:28) Aug 19 23:40:40 volumio volumio[589]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Aug 19 23:40:40 volumio volumio[589]: (node:589) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 14) Aug 19 23:40:40 volumio volumio[589]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 10 Aug 19 23:40:40 volumio sudo[2731]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service Aug 19 23:40:40 volumio sudo[2731]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 23:40:40 volumio volumio[589]: info: Checking Spotify Web API Aug 19 23:40:40 volumio volumio[589]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 19 23:40:40 volumio volumio[589]: Error [ERR_SOCKET_DGRAM_NOT_RUNNING]: Not running Aug 19 23:40:40 volumio volumio[589]: at healthCheck (dgram.js:897:11) Aug 19 23:40:40 volumio volumio[589]: at Socket.send (dgram.js:622:3) Aug 19 23:40:40 volumio volumio[589]: at SpotConnEvents.sendmsg (/data/plugins/music_service/spop/SpotConnController.js:106:21) Aug 19 23:40:40 volumio volumio[589]: at ControllerSpotify.checkWebApi (/data/plugins/music_service/spop/index.js:2326:23) Aug 19 23:40:40 volumio volumio[589]: at Timeout._onTimeout (/data/plugins/music_service/spop/index.js:2418:18) Aug 19 23:40:40 volumio volumio[589]: at listOnTimeout (internal/timers.js:554:17) Aug 19 23:40:40 volumio volumio[589]: at processTimers (internal/timers.js:497:7) { Aug 19 23:40:40 volumio volumio[589]: code: 'ERR_SOCKET_DGRAM_NOT_RUNNING' Aug 19 23:40:40 volumio volumio[589]: } Aug 19 23:40:40 volumio volumio[589]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 19 23:40:40 volumio systemd[1]: Started Volspotconnect2 Daemon. Aug 19 23:40:40 volumio sudo[2731]: pam_unix(sudo:session): session closed for user root Aug 19 23:40:40 volumio volumio[2734]: /usr/lib/startconnect.sh: line 2: ./usr/bin/vollibrespot: No such file or directory Aug 19 23:40:40 volumio systemd[1]: volspotconnect.service: Main process exited, code=exited, status=127/n/a Aug 19 23:40:40 volumio systemd[1]: volspotconnect.service: Failed with result 'exit-code'. Aug 19 23:40:43 volumio systemd[1]: volspotconnect.service: Service RestartSec=2s expired, scheduling restart. Aug 19 23:40:43 volumio systemd[1]: volspotconnect.service: Scheduled restart job, restart counter is at 1. Aug 19 23:40:43 volumio systemd[1]: Stopped Volspotconnect2 Daemon. Aug 19 23:40:43 volumio systemd[1]: Started Volspotconnect2 Daemon. Aug 19 23:40:43 volumio volumio[2743]: /usr/lib/startconnect.sh: line 2: ./usr/bin/vollibrespot: No such file or directory Aug 19 23:40:43 volumio systemd[1]: volspotconnect.service: Main process exited, code=exited, status=127/n/a Aug 19 23:40:43 volumio systemd[1]: volspotconnect.service: Failed with result 'exit-code'. Aug 19 23:40:44 volumio sudo[2747]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-19 23:39 Aug 19 23:40:44 volumio sudo[2747]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="c87c6e2b1ae33dc5801986cbcbe970898b92e7b4" VOLUMIO_FE_VERSION="c433755e569617abac52264d17ad61be303ef463" VOLUMIO_FE3_VERSION="2419ddd8e895c5bdc456962163c9dc7c0f187aa7" VOLUMIO_BE_VERSION="b949d6001d7e656b4668f31c119d2247cdf45158" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 30 Mar 2023 05:45:03 PM CEST" VOLUMIO_VERSION="3.449" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="6428d8e2c06e98483f5c5d77e8f01989"