-- Logs begin at Thu 2019-02-14 12:11:59 EET, end at Fri 2025-03-07 12:45:16 EET. -- Mar 07 12:44:00 volumio2 volumio[983]: info: Enabling plugin Systeminfo Mar 07 12:44:00 volumio2 volumio[983]: info: Loading plugin "Systeminfo"... Mar 07 12:44:00 volumio2 volumio[983]: info: PLUGIN START: Systeminfo Mar 07 12:44:00 volumio2 volumio[983]: info: Done. Mar 07 12:44:12 volumio2 volumio[983]: info: [ASDebug] Port: 24 Mar 07 12:44:12 volumio2 volumio[983]: info: [ASDebug] Inverted: false Mar 07 12:44:12 volumio2 volumio[983]: info: [ASDebug] Delay: 700 Mar 07 12:44:44 volumio2 volumio[983]: info: Enabling plugin spop Mar 07 12:44:44 volumio2 volumio[983]: info: Loading plugin "spop"... Mar 07 12:44:44 volumio2 volumio[983]: info: PLUGIN START: spop Mar 07 12:44:44 volumio2 volumio[983]: info: Creating Spotify config file Mar 07 12:44:44 volumio2 volumio[983]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 12:44:44 volumio2 volumio[983]: info: Done. Mar 07 12:44:44 volumio2 volumio[983]: info: Spotify config file written Mar 07 12:44:44 volumio2 volumio[983]: info: No need to fix Spotify hosts Mar 07 12:44:44 volumio2 sudo[2073]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Mar 07 12:44:44 volumio2 sudo[2073]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 12:44:44 volumio2 systemd[1]: Started go-librespot Daemon. Mar 07 12:44:44 volumio2 go-librespot[2075]: go-librespot daemon starting... Mar 07 12:44:44 volumio2 sudo[2073]: pam_unix(sudo:session): session closed for user root Mar 07 12:44:44 volumio2 go-librespot[2075]: time="2025-03-07T12:44:44+02:00" level=fatal msg="failed loading config" error="failed to unmarshal configuration: 1 error(s) decoding:\n\n* cannot parse 'disable_autoplay' as bool: strconv.ParseBool: parsing \"${disable_autoplay}\": invalid syntax" Mar 07 12:44:44 volumio2 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 07 12:44:44 volumio2 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 07 12:44:45 volumio2 volumio[983]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Mar 07 12:44:45 volumio2 volumio[983]: SPOTIFY: BQCdqkRC4ExXHzPIk1oDCId9zLPmCIpq5aiUWCAcGy24p5u4sDDotFZ3xyi0BAjjVq2-OAH6vHKxEuZkiZ0BEtEOaR8LG9qNaRgvU45BWyOjGvyCkzhoDctkKpcYCltpTzZVCKfGUXERzp7YtEZduFW3ghaE603x3GTtOux4OtWtP5jFUJcRvP3bAf_4MKod3sME9uqx2H1nuwCM0oDYvQFsTaaByycfPDDJ1GwvOD7Ol0YJEt6nZuHAUVQbWM8muSJq1dZtc9i7yOTDfPDBX0NSJDnucL4tMaClRGI9tok Mar 07 12:44:45 volumio2 volumio[983]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Mar 07 12:44:45 volumio2 volumio[983]: info: New Spotify access token = BQCdqkRC4ExXHzPIk1oDCId9zLPmCIpq5aiUWCAcGy24p5u4sDDotFZ3xyi0BAjjVq2-OAH6vHKxEuZkiZ0BEtEOaR8LG9qNaRgvU45BWyOjGvyCkzhoDctkKpcYCltpTzZVCKfGUXERzp7YtEZduFW3ghaE603x3GTtOux4OtWtP5jFUJcRvP3bAf_4MKod3sME9uqx2H1nuwCM0oDYvQFsTaaByycfPDDJ1GwvOD7Ol0YJEt6nZuHAUVQbWM8muSJq1dZtc9i7yOTDfPDBX0NSJDnucL4tMaClRGI9tok Mar 07 12:44:45 volumio2 volumio[983]: info: Spotify credentials grant success - running version from March 24, 2019 Mar 07 12:44:45 volumio2 volumio[983]: SPOTIFY: User informations: {"country":"RO","display_name":"Irina.teican","email":"irina.teican@yahoo.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/31iauloyjtpdxovkep2z7t5rfz5m"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/31iauloyjtpdxovkep2z7t5rfz5m","id":"31iauloyjtpdxovkep2z7t5rfz5m","images":[],"product":"premium","type":"user","uri":"spotify:user:31iauloyjtpdxovkep2z7t5rfz5m"} Mar 07 12:44:45 volumio2 volumio[983]: info: Spotify Successfully logged in Mar 07 12:44:45 volumio2 volumio[983]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 07 12:44:45 volumio2 volumio[983]: info: [1741344285183] CoreMusicLibrary::Adding element Spotify Mar 07 12:44:45 volumio2 volumio[983]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 07 12:44:45 volumio2 volumio[983]: Cannot find translation for source Mixcloud Mar 07 12:44:45 volumio2 volumio[983]: Cannot find translation for source SoundCloud Mar 07 12:44:45 volumio2 volumio[983]: Cannot find translation for source YouTube2 Mar 07 12:44:45 volumio2 volumio[983]: Cannot find translation for source YouTube Music Mar 07 12:44:45 volumio2 volumio[983]: Cannot find translation for source Bandcamp Discover Mar 07 12:44:45 volumio2 volumio[983]: Cannot find translation for source Spotify Mar 07 12:44:47 volumio2 volumio[983]: info: go-librespot daemon successfully initialized Mar 07 12:44:48 volumio2 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 07 12:44:48 volumio2 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Mar 07 12:44:48 volumio2 systemd[1]: Stopped go-librespot Daemon. Mar 07 12:44:48 volumio2 systemd[1]: Started go-librespot Daemon. Mar 07 12:44:48 volumio2 go-librespot[2134]: go-librespot daemon starting... Mar 07 12:44:48 volumio2 go-librespot[2134]: time="2025-03-07T12:44:48+02:00" level=fatal msg="failed loading config" error="failed to unmarshal configuration: 1 error(s) decoding:\n\n* cannot parse 'disable_autoplay' as bool: strconv.ParseBool: parsing \"${disable_autoplay}\": invalid syntax" Mar 07 12:44:48 volumio2 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 07 12:44:48 volumio2 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 07 12:44:50 volumio2 volumio[983]: info: Initializing connection to go-librespot Websocket Mar 07 12:44:50 volumio2 volumio[983]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 07 12:44:51 volumio2 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 07 12:44:51 volumio2 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Mar 07 12:44:51 volumio2 systemd[1]: Stopped go-librespot Daemon. Mar 07 12:44:51 volumio2 systemd[1]: Started go-librespot Daemon. Mar 07 12:44:51 volumio2 go-librespot[2142]: go-librespot daemon starting... Mar 07 12:44:51 volumio2 go-librespot[2142]: time="2025-03-07T12:44:51+02:00" level=fatal msg="failed loading config" error="failed to unmarshal configuration: 1 error(s) decoding:\n\n* cannot parse 'disable_autoplay' as bool: strconv.ParseBool: parsing \"${disable_autoplay}\": invalid syntax" Mar 07 12:44:51 volumio2 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 07 12:44:51 volumio2 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 07 12:44:53 volumio2 volumio[983]: info: Initializing connection to go-librespot Websocket Mar 07 12:44:53 volumio2 volumio[983]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 07 12:44:54 volumio2 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 07 12:44:54 volumio2 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Mar 07 12:44:54 volumio2 systemd[1]: Stopped go-librespot Daemon. Mar 07 12:44:54 volumio2 systemd[1]: Started go-librespot Daemon. Mar 07 12:44:54 volumio2 go-librespot[2152]: go-librespot daemon starting... Mar 07 12:44:54 volumio2 go-librespot[2152]: time="2025-03-07T12:44:54+02:00" level=fatal msg="failed loading config" error="failed to unmarshal configuration: 1 error(s) decoding:\n\n* cannot parse 'disable_autoplay' as bool: strconv.ParseBool: parsing \"${disable_autoplay}\": invalid syntax" Mar 07 12:44:54 volumio2 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 07 12:44:54 volumio2 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 07 12:44:57 volumio2 volumio[983]: info: Initializing connection to go-librespot Websocket Mar 07 12:44:57 volumio2 volumio[983]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 07 12:44:57 volumio2 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 07 12:44:57 volumio2 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Mar 07 12:44:57 volumio2 systemd[1]: Stopped go-librespot Daemon. Mar 07 12:44:57 volumio2 systemd[1]: Started go-librespot Daemon. Mar 07 12:44:58 volumio2 go-librespot[2173]: go-librespot daemon starting... Mar 07 12:44:58 volumio2 go-librespot[2173]: time="2025-03-07T12:44:58+02:00" level=fatal msg="failed loading config" error="failed to unmarshal configuration: 1 error(s) decoding:\n\n* cannot parse 'disable_autoplay' as bool: strconv.ParseBool: parsing \"${disable_autoplay}\": invalid syntax" Mar 07 12:44:58 volumio2 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 07 12:44:58 volumio2 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 07 12:45:00 volumio2 volumio[983]: info: Initializing connection to go-librespot Websocket Mar 07 12:45:00 volumio2 volumio[983]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 07 12:45:01 volumio2 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 07 12:45:01 volumio2 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Mar 07 12:45:01 volumio2 systemd[1]: Stopped go-librespot Daemon. Mar 07 12:45:01 volumio2 systemd[1]: Started go-librespot Daemon. Mar 07 12:45:01 volumio2 go-librespot[2181]: go-librespot daemon starting... Mar 07 12:45:01 volumio2 go-librespot[2181]: time="2025-03-07T12:45:01+02:00" level=fatal msg="failed loading config" error="failed to unmarshal configuration: 1 error(s) decoding:\n\n* cannot parse 'disable_autoplay' as bool: strconv.ParseBool: parsing \"${disable_autoplay}\": invalid syntax" Mar 07 12:45:01 volumio2 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 07 12:45:01 volumio2 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 07 12:45:03 volumio2 volumio[983]: info: Initializing connection to go-librespot Websocket Mar 07 12:45:03 volumio2 volumio[983]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 07 12:45:04 volumio2 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 07 12:45:04 volumio2 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Mar 07 12:45:04 volumio2 systemd[1]: Stopped go-librespot Daemon. Mar 07 12:45:04 volumio2 systemd[1]: Started go-librespot Daemon. Mar 07 12:45:04 volumio2 go-librespot[2188]: go-librespot daemon starting... Mar 07 12:45:04 volumio2 go-librespot[2188]: time="2025-03-07T12:45:04+02:00" level=fatal msg="failed loading config" error="failed to unmarshal configuration: 1 error(s) decoding:\n\n* cannot parse 'disable_autoplay' as bool: strconv.ParseBool: parsing \"${disable_autoplay}\": invalid syntax" Mar 07 12:45:04 volumio2 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 07 12:45:04 volumio2 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 07 12:45:06 volumio2 volumio[983]: info: Initializing connection to go-librespot Websocket Mar 07 12:45:06 volumio2 volumio[983]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 07 12:45:07 volumio2 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 07 12:45:07 volumio2 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Mar 07 12:45:07 volumio2 systemd[1]: Stopped go-librespot Daemon. Mar 07 12:45:07 volumio2 systemd[1]: Started go-librespot Daemon. Mar 07 12:45:07 volumio2 go-librespot[2211]: go-librespot daemon starting... Mar 07 12:45:07 volumio2 go-librespot[2211]: time="2025-03-07T12:45:07+02:00" level=fatal msg="failed loading config" error="failed to unmarshal configuration: 1 error(s) decoding:\n\n* cannot parse 'disable_autoplay' as bool: strconv.ParseBool: parsing \"${disable_autoplay}\": invalid syntax" Mar 07 12:45:07 volumio2 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 07 12:45:07 volumio2 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 07 12:45:09 volumio2 volumio[983]: info: Initializing connection to go-librespot Websocket Mar 07 12:45:09 volumio2 volumio[983]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 07 12:45:10 volumio2 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 07 12:45:10 volumio2 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Mar 07 12:45:10 volumio2 systemd[1]: Stopped go-librespot Daemon. Mar 07 12:45:10 volumio2 systemd[1]: Started go-librespot Daemon. Mar 07 12:45:11 volumio2 go-librespot[2218]: go-librespot daemon starting... Mar 07 12:45:11 volumio2 go-librespot[2218]: time="2025-03-07T12:45:11+02:00" level=fatal msg="failed loading config" error="failed to unmarshal configuration: 1 error(s) decoding:\n\n* cannot parse 'disable_autoplay' as bool: strconv.ParseBool: parsing \"${disable_autoplay}\": invalid syntax" Mar 07 12:45:11 volumio2 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 07 12:45:11 volumio2 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 07 12:45:12 volumio2 volumio[983]: info: Initializing connection to go-librespot Websocket Mar 07 12:45:12 volumio2 volumio[983]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 07 12:45:14 volumio2 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 07 12:45:14 volumio2 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Mar 07 12:45:14 volumio2 systemd[1]: Stopped go-librespot Daemon. Mar 07 12:45:14 volumio2 systemd[1]: Started go-librespot Daemon. Mar 07 12:45:14 volumio2 go-librespot[2229]: go-librespot daemon starting... Mar 07 12:45:14 volumio2 go-librespot[2229]: time="2025-03-07T12:45:14+02:00" level=fatal msg="failed loading config" error="failed to unmarshal configuration: 1 error(s) decoding:\n\n* cannot parse 'disable_autoplay' as bool: strconv.ParseBool: parsing \"${disable_autoplay}\": invalid syntax" Mar 07 12:45:14 volumio2 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 07 12:45:14 volumio2 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 07 12:45:15 volumio2 volumio[983]: info: Initializing connection to go-librespot Websocket Mar 07 12:45:15 volumio2 volumio[983]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 07 12:45:15 volumio2 volumio[983]: info: Starting Uninstall of plugin system_controller - ampswitch Mar 07 12:45:15 volumio2 volumio[983]: info: Uninstalling plugin ampswitch Mar 07 12:45:15 volumio2 volumio[983]: info: [ASDebug] Port: 24 Mar 07 12:45:15 volumio2 volumio[983]: info: [ASDebug] Inverted: false Mar 07 12:45:15 volumio2 volumio[983]: info: [ASDebug] Delay: 700 Mar 07 12:45:15 volumio2 volumio[983]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 07 12:45:15 volumio2 volumio[983]: Error: EBADF: bad file descriptor, close Mar 07 12:45:15 volumio2 volumio[983]: at Object.closeSync (fs.js:439:3) Mar 07 12:45:15 volumio2 volumio[983]: at Object.closeSync (/volumio/node_modules/graceful-fs/graceful-fs.js:74:20) Mar 07 12:45:15 volumio2 volumio[983]: at Object.closeSync (/data/plugins/system_controller/ampswitch/node_modules/graceful-fs/graceful-fs.js:51:27) Mar 07 12:45:15 volumio2 volumio[983]: at Gpio.unexport (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:307:8) Mar 07 12:45:15 volumio2 volumio[983]: at AmpSwitchController.freeGPIO (/data/plugins/system_controller/ampswitch/index.js:242:19) Mar 07 12:45:15 volumio2 volumio[983]: at AmpSwitchController.onStop (/data/plugins/system_controller/ampswitch/index.js:90:10) Mar 07 12:45:15 volumio2 volumio[983]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Mar 07 12:45:15 volumio2 volumio[983]: at PluginManager.unInstallPlugin (/volumio/app/pluginmanager.js:1449:10) Mar 07 12:45:15 volumio2 volumio[983]: at CoreCommandRouter.unInstallPlugin (/volumio/app/index.js:1484:22) Mar 07 12:45:15 volumio2 volumio[983]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1260:45) Mar 07 12:45:15 volumio2 volumio[983]: at Socket.emit (events.js:315:20) Mar 07 12:45:15 volumio2 volumio[983]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Mar 07 12:45:15 volumio2 volumio[983]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Mar 07 12:45:15 volumio2 volumio[983]: errno: -9, Mar 07 12:45:15 volumio2 volumio[983]: syscall: 'close', Mar 07 12:45:15 volumio2 volumio[983]: code: 'EBADF' Mar 07 12:45:15 volumio2 volumio[983]: } Mar 07 12:45:15 volumio2 volumio[983]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 07 12:45:16 volumio2 sudo[2246]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-07 12:44 Mar 07 12:45:16 volumio2 sudo[2246]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="a01a833dd28483f88cb390596f5090b7187992af" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 27 Feb 2025 11:42:54 AM CET" VOLUMIO_VERSION="3.795" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4049a67f1c09d4a94e033ba35a80a144"