-- Logs begin at Sun 2022-07-31 17:02:15 UTC, end at Sun 2024-10-06 17:55:30 UTC. -- Oct 06 17:54:04 volumio volumio[12441]: [Vollibrespot] : Could not connect to server: Connection timed out (os error 110) Oct 06 17:54:05 volumio volumio[750]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/buster/armhf Oct 06 17:54:08 volumio volumio[750]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/buster/armhf Oct 06 17:54:08 volumio volumio[750]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify Oct 06 17:54:08 volumio volumio[750]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 06 17:54:08 volumio volumio[750]: Cannot find translation for sourceMedia Servers Oct 06 17:54:08 volumio volumio[750]: [SpotifyConnect] Closing metadata listener Oct 06 17:54:08 volumio sudo[12488]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volspotconnect.service Oct 06 17:54:08 volumio sudo[12488]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 17:54:08 volumio systemd[1]: Stopping Volspotconnect2 Daemon... Oct 06 17:54:08 volumio systemd[1]: volspotconnect.service: Main process exited, code=killed, status=15/TERM Oct 06 17:54:08 volumio systemd[1]: volspotconnect.service: Succeeded. Oct 06 17:54:08 volumio systemd[1]: Stopped Volspotconnect2 Daemon. Oct 06 17:54:08 volumio sudo[12488]: pam_unix(sudo:session): session closed for user root Oct 06 17:54:08 volumio volumio[750]: [SpotifyConnect] Vollibrespot Daemon service stoped! Oct 06 17:54:08 volumio volumio[750]: info: Check plugin dependencies Oct 06 17:54:08 volumio volumio[750]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 06 17:54:08 volumio volumio[750]: info: Rename folder Oct 06 17:54:08 volumio volumio[750]: info: Folder /tmp/downloaded_plugin.zip removed Oct 06 17:54:08 volumio volumio[750]: info: Move to category Oct 06 17:54:10 volumio volumio[750]: info: Checking if install.sh is present Oct 06 17:54:10 volumio volumio[750]: info: Executing install.sh Oct 06 17:54:10 volumio sudo[12506]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/spop/install.sh Oct 06 17:54:10 volumio sudo[12506]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 17:54:10 volumio volumio[750]: info: Installing Go-librespot Oct 06 17:54:10 volumio volumio[750]: info: Checking old vollibrespot installs Oct 06 17:54:10 volumio systemd[1]: Reloading. Oct 06 17:54:11 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. Oct 06 17:54:11 volumio systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed. Oct 06 17:54:11 volumio systemd[1]: Reloading. Oct 06 17:54:12 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. Oct 06 17:54:12 volumio volumio[750]: info: Clearing old vollibrespot Oct 06 17:54:12 volumio volumio[750]: info: vollibrespot cleared Oct 06 17:54:12 volumio volumio[750]: info: Dowloading daemon Oct 06 17:54:14 volumio volumio[750]: info: Creating Start Script Oct 06 17:54:14 volumio systemd[1]: Reloading. Oct 06 17:54:14 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. Oct 06 17:54:14 volumio sudo[12506]: pam_unix(sudo:session): session closed for user root Oct 06 17:54:14 volumio volumio[750]: Plugin install end detected on script Oct 06 17:54:14 volumio volumio[750]: info: Install script completed Oct 06 17:54:14 volumio volumio[750]: info: Done installing plugin. Oct 06 17:54:14 volumio volumio[750]: info: Enabling plugin spop Oct 06 17:54:14 volumio volumio[750]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 06 17:54:14 volumio volumio[750]: info: [{"prettyName":"FusionDsp","name":"fusiondsp","category":"audio_interface","version":"1.0.18","icon":"fa-sliders fa-rotate-90","isManuallyInstalled":false,"enabled":false,"active":false},{"prettyName":"Spotify","name":"spop","category":"music_service","version":"4.1.1","icon":"fa-spotify","isManuallyInstalled":false,"enabled":true,"active":false}] Oct 06 17:54:14 volumio volumio[750]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 06 17:54:14 volumio volumio[750]: info: Folder /tmp/plugins removed Oct 06 17:54:14 volumio volumio[750]: info: Folder /tmp/downloaded_plugin.zip removed Oct 06 17:54:14 volumio volumio[750]: info: Folder /data/temp removed Oct 06 17:54:23 volumio volumio[750]: info: Airplay started streaming, receiving metadatas Oct 06 17:54:23 volumio volumio[750]: info: CorePlayQueue::getTrack 0 Oct 06 17:54:23 volumio volumio[750]: info: CoreCommandRouter::volumioStop Oct 06 17:54:23 volumio volumio[750]: info: CoreStateMachine::stop Oct 06 17:54:23 volumio volumio[750]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 06 17:54:23 volumio volumio[750]: verbose: UNSET VOLATILE Oct 06 17:54:23 volumio volumio[750]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 06 17:54:23 volumio volumio[750]: info: Airplay started streaming, receiving metadatas Oct 06 17:54:23 volumio volumio[750]: info: CorePlayQueue::getTrack 0 Oct 06 17:54:23 volumio volumio[750]: info: CoreCommandRouter::volumioStop Oct 06 17:54:23 volumio volumio[750]: info: CoreStateMachine::stop Oct 06 17:54:23 volumio volumio[750]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 06 17:54:23 volumio volumio[750]: verbose: UNSET VOLATILE Oct 06 17:54:23 volumio volumio[750]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 06 17:54:23 volumio volumio[750]: info: Airplay started streaming, receiving metadatas Oct 06 17:54:23 volumio volumio[750]: info: CorePlayQueue::getTrack 0 Oct 06 17:54:23 volumio volumio[750]: info: CoreCommandRouter::volumioStop Oct 06 17:54:23 volumio volumio[750]: info: CoreStateMachine::stop Oct 06 17:54:23 volumio volumio[750]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 06 17:54:23 volumio volumio[750]: verbose: UNSET VOLATILE Oct 06 17:54:23 volumio volumio[750]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 06 17:54:23 volumio volumio[750]: info: Airplay started streaming, receiving metadatas Oct 06 17:54:23 volumio volumio[750]: info: CorePlayQueue::getTrack 0 Oct 06 17:54:23 volumio volumio[750]: info: CoreCommandRouter::volumioStop Oct 06 17:54:23 volumio volumio[750]: info: CoreStateMachine::stop Oct 06 17:54:23 volumio volumio[750]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 06 17:54:23 volumio volumio[750]: verbose: UNSET VOLATILE Oct 06 17:54:23 volumio volumio[750]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 06 17:54:23 volumio shairport-sync[12443]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 06 17:54:23 volumio shairport-sync[12443]: Dload Upload Total Spent Left Speed Oct 06 17:54:23 volumio volumio[750]: info: Oct 06 17:54:23 volumio volumio[750]: ---------------------------- Client requests Start Airplay PlaybackRoutine Oct 06 17:54:23 volumio volumio[750]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback Oct 06 17:54:23 volumio volumio[750]: info: Airplay playback start Oct 06 17:54:23 volumio volumio[750]: info: CorePlayQueue::getTrack 0 Oct 06 17:54:23 volumio volumio[750]: info: CoreCommandRouter::volumioStop Oct 06 17:54:23 volumio volumio[750]: info: CoreStateMachine::stop Oct 06 17:54:23 volumio volumio[750]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 06 17:54:23 volumio volumio[750]: verbose: UNSET VOLATILE Oct 06 17:54:23 volumio volumio[750]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 06 17:54:23 volumio shairport-sync[12443]: [158B blob data] Oct 06 17:54:23 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Oct 06 17:54:24 volumio volumio[750]: info: CoreCommandRouter::servicePushState Oct 06 17:54:24 volumio volumio[750]: info: CoreStateMachine::pushState Oct 06 17:54:24 volumio volumio[750]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 17:54:24 volumio volumio[750]: info: CoreCommandRouter::volumioPushState Oct 06 17:54:24 volumio volumio[750]: info: CoreCommandRouter::servicePushState Oct 06 17:54:24 volumio volumio[750]: info: CoreStateMachine::pushState Oct 06 17:54:24 volumio volumio[750]: info: CoreCommandRouter::volumioPushState Oct 06 17:54:24 volumio volumio[750]: info: CoreCommandRouter::servicePushState Oct 06 17:54:24 volumio volumio[750]: info: CoreStateMachine::pushState Oct 06 17:54:24 volumio volumio[750]: info: CoreCommandRouter::volumioPushState Oct 06 17:54:24 volumio volumio[750]: info: CoreCommandRouter::servicePushState Oct 06 17:54:24 volumio volumio[750]: info: CoreStateMachine::pushState Oct 06 17:54:24 volumio volumio[750]: info: CoreCommandRouter::volumioPushState Oct 06 17:54:24 volumio volumio[750]: info: CoreCommandRouter::servicePushState Oct 06 17:54:24 volumio volumio[750]: info: CoreStateMachine::pushState Oct 06 17:54:24 volumio volumio[750]: info: CoreCommandRouter::volumioPushState Oct 06 17:54:25 volumio volumio[750]: info: CoreCommandRouter::servicePushState Oct 06 17:54:25 volumio volumio[750]: info: CoreStateMachine::pushState Oct 06 17:54:25 volumio volumio[750]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 17:54:25 volumio volumio[750]: info: CoreCommandRouter::volumioPushState Oct 06 17:54:25 volumio volumio[750]: info: CoreCommandRouter::servicePushState Oct 06 17:54:25 volumio volumio[750]: info: CoreStateMachine::pushState Oct 06 17:54:25 volumio volumio[750]: info: CoreCommandRouter::volumioPushState Oct 06 17:54:25 volumio volumio[750]: info: CoreCommandRouter::servicePushState Oct 06 17:54:25 volumio volumio[750]: info: CoreStateMachine::pushState Oct 06 17:54:25 volumio volumio[750]: info: CoreCommandRouter::volumioPushState Oct 06 17:54:25 volumio volumio[750]: info: CoreCommandRouter::servicePushState Oct 06 17:54:25 volumio volumio[750]: info: CoreStateMachine::pushState Oct 06 17:54:25 volumio volumio[750]: info: CoreCommandRouter::volumioPushState Oct 06 17:54:25 volumio volumio[750]: info: CoreCommandRouter::servicePushState Oct 06 17:54:25 volumio volumio[750]: info: CoreStateMachine::pushState Oct 06 17:54:25 volumio volumio[750]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 17:54:25 volumio volumio[750]: info: CoreCommandRouter::volumioPushState Oct 06 17:54:25 volumio volumio[750]: info: CoreCommandRouter::servicePushState Oct 06 17:54:25 volumio volumio[750]: info: CoreStateMachine::pushState Oct 06 17:54:25 volumio volumio[750]: info: CoreCommandRouter::volumioPushState Oct 06 17:54:25 volumio volumio[750]: info: CoreCommandRouter::servicePushState Oct 06 17:54:25 volumio volumio[750]: info: CoreStateMachine::pushState Oct 06 17:54:25 volumio volumio[750]: info: CoreCommandRouter::volumioPushState Oct 06 17:54:25 volumio volumio[750]: info: CoreCommandRouter::servicePushState Oct 06 17:54:25 volumio volumio[750]: info: CoreStateMachine::pushState Oct 06 17:54:25 volumio volumio[750]: info: CoreCommandRouter::volumioPushState Oct 06 17:54:36 volumio kernel: Under-voltage detected! (0x00050005) Oct 06 17:54:41 volumio kernel: Voltage normalised (0x00000000) Oct 06 17:55:03 volumio volumio[750]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 06 17:55:03 volumio volumio[750]: error: Cannot populate Spotify configuration: TypeError: Cannot set property 'hidden' of undefined Oct 06 17:55:09 volumio volumio[750]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 06 17:55:09 volumio volumio[750]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 06 17:55:11 volumio volumio[750]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 06 17:55:11 volumio volumio[750]: error: Cannot populate Spotify configuration: TypeError: Cannot set property 'hidden' of undefined Oct 06 17:55:15 volumio sudo[12580]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 06 17:55:15 volumio sudo[12580]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 17:55:15 volumio sudo[12582]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 06 17:55:15 volumio sudo[12582]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 17:55:15 volumio sudo[12582]: pam_unix(sudo:session): session closed for user root Oct 06 17:55:15 volumio sudo[12580]: pam_unix(sudo:session): session closed for user root Oct 06 17:55:15 volumio volumio[750]: verbose: New Socket.io Connection to 192.168.1.205 from 192.168.1.183 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_0 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) CriOS/129.0.6668.69 Mobile/15E148 Safari/604.1 Total Clients: 4 Oct 06 17:55:15 volumio volumio[750]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 06 17:55:15 volumio volumio[750]: error: Cannot populate Spotify configuration: TypeError: Cannot set property 'hidden' of undefined Oct 06 17:55:16 volumio volumio[750]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 06 17:55:16 volumio volumio[750]: info: CoreCommandRouter::volumioGetState Oct 06 17:55:16 volumio volumio[750]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 06 17:55:16 volumio volumio[750]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 06 17:55:16 volumio volumio[750]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 06 17:55:16 volumio volumio[750]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 06 17:55:18 volumio volumio[750]: info: CoreCommandRouter::volumioGetVisibleSources Oct 06 17:55:18 volumio volumio[750]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 06 17:55:18 volumio volumio[750]: info: CoreCommandRouter::volumioGetQueue Oct 06 17:55:18 volumio volumio[750]: info: CoreStateMachine::getQueue Oct 06 17:55:18 volumio volumio[750]: info: CorePlayQueue::getQueue Oct 06 17:55:18 volumio volumio[750]: info: Listing playlists Oct 06 17:55:22 volumio volumio[750]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 06 17:55:22 volumio volumio[750]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 06 17:55:22 volumio volumio[750]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 06 17:55:25 volumio volumio[750]: info: Enabling plugin spop Oct 06 17:55:25 volumio volumio[750]: info: Loading plugin "spop"... Oct 06 17:55:25 volumio volumio[750]: info: PLUGIN START: spop Oct 06 17:55:25 volumio volumio[750]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 06 17:55:25 volumio volumio[750]: [SpotifyConnect] Creating VLS config file Oct 06 17:55:25 volumio volumio[750]: error: Failed to read template file: Error: ENOENT: no such file or directory, open '/data/plugins/music_service/spop/volspotify.tmpl' Oct 06 17:55:25 volumio volumio[750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 06 17:55:25 volumio volumio[750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 06 17:55:25 volumio volumio[750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 06 17:55:25 volumio volumio[750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 06 17:55:25 volumio volumio[750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 06 17:55:25 volumio volumio[750]: [SpotifyConnect] Starting metadata listener Oct 06 17:55:25 volumio sudo[12592]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hosts Oct 06 17:55:25 volumio sudo[12592]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 17:55:25 volumio sudo[12592]: pam_unix(sudo:session): session closed for user root Oct 06 17:55:25 volumio volumio[750]: (node:750) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'replace' of undefined Oct 06 17:55:25 volumio volumio[750]: at ControllerSpotify.createConfigFile (/data/plugins/music_service/spop/index.js:2524:27) Oct 06 17:55:25 volumio volumio[750]: at ControllerSpotify.init (/data/plugins/music_service/spop/index.js:2385:14) Oct 06 17:55:25 volumio volumio[750]: at ControllerSpotify.onStart (/data/plugins/music_service/spop/index.js:133:10) Oct 06 17:55:25 volumio volumio[750]: at PluginManager.startPlugin (/volumio/app/pluginmanager.js:431:30) Oct 06 17:55:25 volumio volumio[750]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Oct 06 17:55:25 volumio volumio[750]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Oct 06 17:55:25 volumio volumio[750]: (node:750) 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: 3) Oct 06 17:55:25 volumio sudo[12595]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service Oct 06 17:55:25 volumio sudo[12595]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 17:55:25 volumio volumio[750]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 5 Oct 06 17:55:25 volumio systemd[1]: Started Volspotconnect2 Daemon. Oct 06 17:55:25 volumio sudo[12595]: pam_unix(sudo:session): session closed for user root Oct 06 17:55:25 volumio volumio[750]: [SpotifyConnect] Vollibrespot Daemon service started! Oct 06 17:55:25 volumio volumio[750]: [Metrics] SpotifyConnect: 0s 102.73ms Oct 06 17:55:25 volumio volumio[750]: info: Done. Oct 06 17:55:25 volumio volumio[12597]: /usr/lib/startconnect.sh: line 2: ./usr/bin/vollibrespot: No such file or directory Oct 06 17:55:25 volumio systemd[1]: volspotconnect.service: Main process exited, code=exited, status=127/n/a Oct 06 17:55:25 volumio systemd[1]: volspotconnect.service: Failed with result 'exit-code'. Oct 06 17:55:26 volumio volumio[750]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify Oct 06 17:55:26 volumio volumio[750]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 06 17:55:26 volumio volumio[750]: Cannot find translation for sourceMedia Servers Oct 06 17:55:26 volumio volumio[750]: [SpotifyConnect] Closing metadata listener Oct 06 17:55:26 volumio volumio[750]: info: Disabling plugin spop Oct 06 17:55:26 volumio volumio[750]: info: Done. Oct 06 17:55:26 volumio sudo[12601]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volspotconnect.service Oct 06 17:55:26 volumio sudo[12601]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 17:55:26 volumio systemd[1]: Stopped Volspotconnect2 Daemon. Oct 06 17:55:26 volumio sudo[12601]: pam_unix(sudo:session): session closed for user root Oct 06 17:55:26 volumio volumio[750]: [SpotifyConnect] Vollibrespot Daemon service stoped! Oct 06 17:55:28 volumio volumio[750]: info: Enabling plugin spop Oct 06 17:55:28 volumio volumio[750]: info: Loading plugin "spop"... Oct 06 17:55:28 volumio volumio[750]: info: PLUGIN START: spop Oct 06 17:55:28 volumio volumio[750]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 06 17:55:28 volumio volumio[750]: [SpotifyConnect] Creating VLS config file Oct 06 17:55:28 volumio volumio[750]: error: Failed to read template file: Error: ENOENT: no such file or directory, open '/data/plugins/music_service/spop/volspotify.tmpl' Oct 06 17:55:28 volumio volumio[750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 06 17:55:28 volumio volumio[750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 06 17:55:28 volumio volumio[750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 06 17:55:28 volumio volumio[750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 06 17:55:28 volumio volumio[750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 06 17:55:28 volumio volumio[750]: [SpotifyConnect] Starting metadata listener Oct 06 17:55:28 volumio sudo[12604]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hosts Oct 06 17:55:28 volumio sudo[12604]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 17:55:28 volumio sudo[12604]: pam_unix(sudo:session): session closed for user root Oct 06 17:55:28 volumio volumio[750]: (node:750) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'replace' of undefined Oct 06 17:55:28 volumio volumio[750]: at ControllerSpotify.createConfigFile (/data/plugins/music_service/spop/index.js:2524:27) Oct 06 17:55:28 volumio volumio[750]: at ControllerSpotify.init (/data/plugins/music_service/spop/index.js:2385:14) Oct 06 17:55:28 volumio volumio[750]: at ControllerSpotify.onStart (/data/plugins/music_service/spop/index.js:133:10) Oct 06 17:55:28 volumio volumio[750]: at PluginManager.startPlugin (/volumio/app/pluginmanager.js:431:30) Oct 06 17:55:28 volumio volumio[750]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Oct 06 17:55:28 volumio volumio[750]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Oct 06 17:55:28 volumio volumio[750]: (node:750) 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: 4) Oct 06 17:55:28 volumio volumio[750]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 5 Oct 06 17:55:28 volumio sudo[12607]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service Oct 06 17:55:28 volumio sudo[12607]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 17:55:28 volumio systemd[1]: Started Volspotconnect2 Daemon. Oct 06 17:55:28 volumio sudo[12607]: pam_unix(sudo:session): session closed for user root Oct 06 17:55:28 volumio volumio[12609]: /usr/lib/startconnect.sh: line 2: ./usr/bin/vollibrespot: No such file or directory Oct 06 17:55:28 volumio volumio[750]: [SpotifyConnect] Vollibrespot Daemon service started! Oct 06 17:55:28 volumio volumio[750]: [Metrics] SpotifyConnect: 0s 95.06ms Oct 06 17:55:28 volumio volumio[750]: info: Done. Oct 06 17:55:28 volumio systemd[1]: volspotconnect.service: Main process exited, code=exited, status=127/n/a Oct 06 17:55:28 volumio systemd[1]: volspotconnect.service: Failed with result 'exit-code'. Oct 06 17:55:29 volumio volumio[750]: info: Checking Spotify Web API Oct 06 17:55:29 volumio volumio[750]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 06 17:55:29 volumio volumio[750]: Error [ERR_SOCKET_DGRAM_NOT_RUNNING]: Not running Oct 06 17:55:29 volumio volumio[750]: at healthCheck (dgram.js:897:11) Oct 06 17:55:29 volumio volumio[750]: at Socket.send (dgram.js:622:3) Oct 06 17:55:29 volumio volumio[750]: at SpotConnEvents.sendmsg (/data/plugins/music_service/spop/SpotConnController.js:106:21) Oct 06 17:55:29 volumio volumio[750]: at ControllerSpotify.checkWebApi (/data/plugins/music_service/spop/index.js:2292:23) Oct 06 17:55:29 volumio volumio[750]: at Timeout._onTimeout (/data/plugins/music_service/spop/index.js:2390:18) Oct 06 17:55:29 volumio volumio[750]: at listOnTimeout (internal/timers.js:554:17) Oct 06 17:55:29 volumio volumio[750]: at processTimers (internal/timers.js:497:7) { Oct 06 17:55:29 volumio volumio[750]: code: 'ERR_SOCKET_DGRAM_NOT_RUNNING' Oct 06 17:55:29 volumio volumio[750]: } Oct 06 17:55:29 volumio volumio[750]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 06 17:55:30 volumio sudo[12620]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-06 17:54 Oct 06 17:55:30 volumio sudo[12620]: 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="3cfa1502993c2310fc263d6ebda1707071fe4cc2" VOLUMIO_FE_VERSION="8efe4f08f83e5de574651b8ed2333629c140d365" VOLUMIO_FE3_VERSION="8f1d07fcde806ea34f52cff908ec76359e14970e" VOLUMIO_BE_VERSION="e249dfa388452e6b8443121dc9b4cabca459c531" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 09 Jun 2022 05:55:51 PM CEST" VOLUMIO_VERSION="3.301" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="6a8974e587cd4bab189d231f79b65462"