-- Logs begin at Thu 2019-02-14 11:12:07 CET, end at Tue 2025-06-24 13:46:12 CEST. -- Jun 24 13:45:06 volumio volumio[699]: info: PLUGIN START: spop Jun 24 13:45:06 volumio volumio[699]: info: Creating Spotify config file Jun 24 13:45:06 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 13:45:07 volumio volumio[699]: info: Done. Jun 24 13:45:07 volumio volumio[699]: info: Spotify config file written Jun 24 13:45:07 volumio volumio[699]: info: No need to fix Spotify hosts Jun 24 13:45:07 volumio sudo[2305]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jun 24 13:45:07 volumio sudo[2305]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 13:45:07 volumio volumio[699]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 24 13:45:07 volumio systemd[1]: Started go-librespot Daemon. Jun 24 13:45:07 volumio go-librespot[2307]: go-librespot daemon starting... Jun 24 13:45:07 volumio sudo[2305]: pam_unix(sudo:session): session closed for user root Jun 24 13:45:07 volumio go-librespot[2307]: time="2025-06-24T13:45:07+02:00" level=info msg="running go-librespot 0.2.0" Jun 24 13:45:07 volumio go-librespot[2307]: time="2025-06-24T13:45:07+02:00" level=debug msg="no app state found" Jun 24 13:45:07 volumio go-librespot[2307]: time="2025-06-24T13:45:07+02:00" level=debug msg="stored credentials not found" Jun 24 13:45:07 volumio go-librespot[2307]: time="2025-06-24T13:45:07+02:00" level=info msg="generated new device id: 3268f7e40e09f1790fc4e3fab29d6901458941a5" Jun 24 13:45:08 volumio go-librespot[2307]: time="2025-06-24T13:45:08+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 24 13:45:11 volumio go-librespot[2307]: time="2025-06-24T13:45:11+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Jun 24 13:45:11 volumio go-librespot[2307]: time="2025-06-24T13:45:11+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jun 24 13:45:11 volumio go-librespot[2307]: time="2025-06-24T13:45:11+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jun 24 13:45:11 volumio go-librespot[2307]: time="2025-06-24T13:45:11+02:00" level=info msg="zeroconf server listening on port 36169" Jun 24 13:45:11 volumio volumio[699]: info: FusionDsp - >>>>>>>>>>>>> armv6l Jun 24 13:45:11 volumio volumio[699]: info: FusionDsp - Convolution not available for cpu armv6l ! Jun 24 13:45:11 volumio volumio[699]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/buster/armhf Jun 24 13:45:12 volumio volumio[699]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/buster/armhf Jun 24 13:45:12 volumio volumio[699]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/buster/armhf Jun 24 13:45:15 volumio volumio[699]: info: go-librespot daemon successfully initialized Jun 24 13:45:18 volumio volumio[699]: info: Initializing connection to go-librespot Websocket Jun 24 13:45:18 volumio go-librespot[2307]: time="2025-06-24T13:45:18+02:00" level=debug msg="new websocket client" Jun 24 13:45:19 volumio volumio[699]: info: Connection to go-librespot Websocket established Jun 24 13:45:21 volumio volumio[699]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/buster/armhf Jun 24 13:45:21 volumio volumio[699]: info: Folder /tmp/plugins removed Jun 24 13:45:21 volumio volumio[699]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/buster/armhf Jun 24 13:45:22 volumio volumio[699]: info: Folder /tmp/plugins removed Jun 24 13:45:22 volumio volumio[699]: info: Getting Spotify volume Jun 24 13:45:23 volumio volumio[699]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jun 24 13:45:23 volumio volumio[699]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/buster/armhf Jun 24 13:45:23 volumio volumio[699]: info: Folder /tmp/plugins removed Jun 24 13:45:24 volumio volumio[699]: info: CoreCommandRouter::volumioGetState Jun 24 13:45:24 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Jun 24 13:45:26 volumio volumio[699]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Jun 24 13:45:26 volumio volumio[699]: SPOTIFY: SPOTIFY VOLUME undefined Jun 24 13:45:26 volumio volumio[699]: SPOTIFY: VOLUMIO VOLUME 86 Jun 24 13:45:26 volumio volumio[699]: info: Aligning Spotify Volume to Volumio Volume Jun 24 13:45:26 volumio volumio[699]: info: CoreCommandRouter::volumioGetState Jun 24 13:45:26 volumio volumio[699]: info: CorePlayQueue::getTrack 0 Jun 24 13:45:26 volumio volumio[699]: info: Setting Spotify Volume from Volumio: 86 Jun 24 13:45:26 volumio volumio[699]: SPOTIFY: SETTING SPOTIFY VOLUME 86 Jun 24 13:45:26 volumio volumio[699]: info: Sending Spotify command with payload to local API: /player/volume Jun 24 13:45:46 volumio volumio[699]: info: Check plugin dependencies Jun 24 13:45:47 volumio volumio[699]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 24 13:45:47 volumio volumio[699]: info: Checking if plugin already exists Jun 24 13:45:47 volumio volumio[699]: info: An error occurred installing the plugin. Rolling back config Jun 24 13:45:47 volumio volumio[699]: info: Plugin folders cleanup Jun 24 13:45:47 volumio volumio[699]: info: Scanning into folder /volumio/app/plugins/ Jun 24 13:45:47 volumio volumio[699]: info: Scanning category audio_interface Jun 24 13:45:47 volumio volumio[699]: info: Scanning category miscellanea Jun 24 13:45:47 volumio volumio[699]: info: Scanning category music_service Jun 24 13:45:47 volumio volumio[699]: info: Scanning category plugins.json Jun 24 13:45:47 volumio volumio[699]: info: Scanning category system_controller Jun 24 13:45:47 volumio volumio[699]: info: Scanning category user_interface Jun 24 13:45:47 volumio volumio[699]: info: Scanning into folder /data/plugins/ Jun 24 13:45:47 volumio volumio[699]: info: Scanning category audio_interface Jun 24 13:45:47 volumio volumio[699]: info: Scanning category music_service Jun 24 13:45:47 volumio volumio[699]: info: Scanning category system_controller Jun 24 13:45:47 volumio volumio[699]: info: Scanning category user_interface Jun 24 13:45:47 volumio volumio[699]: info: Plugin folders cleanup completed Jun 24 13:45:47 volumio volumio[699]: info: Error: Error Jun 24 13:45:47 volumio volumio[699]: info: Folder /tmp/plugins removed Jun 24 13:45:47 volumio volumio[699]: info: Folder /tmp/downloaded_plugin.zip removed Jun 24 13:45:47 volumio volumio[699]: info: Folder /tmp/downloaded_plugin.zip removed Jun 24 13:46:03 volumio volumio[699]: info: Folder /data/temp removed Jun 24 13:46:03 volumio volumio[699]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 24 13:46:04 volumio volumio[699]: Error: Unable to resolve or reject the same promise twice Jun 24 13:46:04 volumio volumio[699]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Jun 24 13:46:04 volumio volumio[699]: at /volumio/app/pluginmanager.js:1087:11 Jun 24 13:46:04 volumio volumio[699]: at CB (/volumio/node_modules/fs-extra/lib/remove/rimraf.js:57:5) Jun 24 13:46:04 volumio volumio[699]: at /volumio/node_modules/fs-extra/lib/remove/rimraf.js:213:24 Jun 24 13:46:04 volumio volumio[699]: at CB (/volumio/node_modules/fs-extra/lib/remove/rimraf.js:57:5) Jun 24 13:46:04 volumio volumio[699]: at /volumio/node_modules/fs-extra/lib/remove/rimraf.js:213:24 Jun 24 13:46:04 volumio volumio[699]: at CB (/volumio/node_modules/fs-extra/lib/remove/rimraf.js:57:5) Jun 24 13:46:04 volumio volumio[699]: at /volumio/node_modules/fs-extra/lib/remove/rimraf.js:213:24 Jun 24 13:46:04 volumio volumio[699]: at CB (/volumio/node_modules/fs-extra/lib/remove/rimraf.js:57:5) Jun 24 13:46:04 volumio volumio[699]: at FSReqCallback.oncomplete (fs.js:171:23) Jun 24 13:46:04 volumio volumio[699]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 24 13:46:11 volumio ntpd[563]: 195.32.70.195 local addr 192.168.1.41 -> Jun 24 13:46:12 volumio ntpd[563]: 212.6.50.243 local addr 192.168.1.41 -> Jun 24 13:46:12 volumio sudo[2483]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-24 13:45 Jun 24 13:46:12 volumio sudo[2483]: 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="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"